2020-11-12T18:51:20.126Z,1605207080.126 [DataOverHttps](INFO): Moved sent file to Logs/20201111T205043/Express0145.lzma.bak
2020-11-12T18:51:20.126Z,1605207080.126 [DataOverHttps](INFO): SBD MOMSN=13194682
2020-11-12T18:51:41.002Z,1605207101.002 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201111T205043/Courier0147.lzma
2020-11-12T18:51:42.005Z,1605207102.005 [DataOverHttps](INFO): Moved sent file to Logs/20201111T205043/Courier0147.lzma.bak
2020-11-12T18:51:42.005Z,1605207102.005 [DataOverHttps](INFO): SBD MOMSN=13194685
2020-11-12T18:51:58.326Z,1605207118.326 [DataOverHttps](INFO): Sending 137 bytes from file Logs/20201111T205043/Express0148.lzma
2020-11-12T18:51:59.330Z,1605207119.330 [DataOverHttps](INFO): Moved sent file to Logs/20201111T205043/Express0148.lzma.bak
2020-11-12T18:51:59.330Z,1605207119.330 [DataOverHttps](INFO): SBD MOMSN=13194687
2020-11-12T18:52:00.829Z,1605207120.829 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T18:52:00.829Z,1605207120.829 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T18:52:00.829Z,1605207120.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T18:53:06.076Z,1605207186.076 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:53:09.088Z,1605207189.088 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251434
2020-11-12T18:53:41.215Z,1605207221.215 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:54:16.355Z,1605207256.355 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:54:51.496Z,1605207291.496 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:55:26.648Z,1605207326.648 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:56:01.787Z,1605207361.787 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-11-12T18:56:18.477Z,1605207378.477 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009339
2020-11-12T18:57:01.434Z,1605207421.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T18:57:01.434Z,1605207421.434 [Default:CheckIn:C.Wait] Stopped
2020-11-12T18:57:01.434Z,1605207421.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T18:57:01.434Z,1605207421.434 [Default:CheckIn:D] Running Loop=1
2020-11-12T18:57:01.845Z,1605207421.845 [Default:CheckIn:D] Stopped
2020-11-12T18:57:01.846Z,1605207421.846 [Default:CheckIn:E] Running Loop=1
2020-11-12T18:57:02.250Z,1605207422.250 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.253607 min 
2020-11-12T18:57:02.250Z,1605207422.250 [Default:CheckIn:E] Stopped
2020-11-12T18:57:02.250Z,1605207422.250 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T18:57:02.250Z,1605207422.250 [Default:CheckIn] Stopped
2020-11-12T18:57:02.250Z,1605207422.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T18:57:02.251Z,1605207422.251 [Default:CheckIn](INFO): Running loop #2
2020-11-12T18:57:02.251Z,1605207422.251 [Default:CheckIn] Running Loop=2
2020-11-12T18:57:02.251Z,1605207422.251 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T18:57:02.251Z,1605207422.251 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T18:57:04.249Z,1605207424.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185703.00,A,3648.28412,N,12147.86409,W,27.389,85.49,121120,,,D*71
2020-11-12T18:57:04.251Z,1605207424.251 [NAL9602](INFO): GPS fix at 20201112T185703: (36.804735, -121.797735)
2020-11-12T18:57:04.262Z,1605207424.262 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T18:57:04.262Z,1605207424.262 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T18:57:11.859Z,1605207431.859 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20201112T185118/Courier0003.lzma
2020-11-12T18:57:12.861Z,1605207432.861 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0003.lzma.bak
2020-11-12T18:57:12.861Z,1605207432.861 [DataOverHttps](INFO): SBD MOMSN=13194794
2020-11-12T18:57:30.215Z,1605207450.215 [DataOverHttps](INFO): Sending 395 bytes from file Logs/20201112T185118/Express0004.lzma
2020-11-12T18:57:31.217Z,1605207451.217 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0004.lzma.bak
2020-11-12T18:57:31.217Z,1605207451.217 [DataOverHttps](INFO): SBD MOMSN=13194799
2020-11-12T18:57:32.543Z,1605207452.543 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T18:57:32.544Z,1605207452.544 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T18:57:32.544Z,1605207452.544 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T18:57:34.956Z,1605207454.956 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T18:57:35.031Z,1605207455.031 [NAL9602](FAULT): received: 
+CSQ:0

OK376, 2, 0, 0, 0

OK
2020-11-12T18:57:35.031Z,1605207455.031 [NAL9602] Data Fault, FailCount= 2
2020-11-12T18:57:35.031Z,1605207455.031 [NAL9602](ERROR): Data Fault
2020-11-12T18:57:35.120Z,1605207455.120 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T18:57:35.356Z,1605207455.356 [NAL9602](INFO): Powering down
2020-11-12T18:57:36.232Z,1605207456.232 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T18:57:36.233Z,1605207456.233 [NAL9602] No Fault, FailCount= 2
2020-11-12T18:58:05.660Z,1605207485.660 [NAL9602](INFO): Powering up NAL9602
2020-11-12T18:58:16.568Z,1605207496.568 [NAL9602](INFO): NAL9602 initialized
2020-11-12T18:58:47.680Z,1605207527.680 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:00:42.834Z,1605207642.834 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-12T19:01:18.797Z,1605207678.797 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-12T19:02:33.202Z,1605207753.202 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:02:33.202Z,1605207753.202 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:02:33.202Z,1605207753.202 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:02:33.202Z,1605207753.202 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:02:33.630Z,1605207753.630 [Default:CheckIn:D] Stopped
2020-11-12T19:02:33.631Z,1605207753.631 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.783358 min 
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn:E] Stopped
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn] Stopped
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn](INFO): Running loop #3
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn] Running Loop=3
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:02:34.017Z,1605207754.017 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:02:36.018Z,1605207756.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190234.00,A,3648.25199,N,12147.14730,W,4.976,197.35,121120,,,A*7A
2020-11-12T19:02:36.020Z,1605207756.020 [NAL9602](INFO): GPS fix at 20201112T190234: (36.804200, -121.785788)
2020-11-12T19:02:36.068Z,1605207756.068 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:02:36.068Z,1605207756.068 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:02:38.073Z,1605207758.073 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:02:43.666Z,1605207763.666 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0006.lzma
2020-11-12T19:02:44.669Z,1605207764.669 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0006.lzma.bak
2020-11-12T19:02:44.669Z,1605207764.669 [DataOverHttps](INFO): SBD MOMSN=13194920
2020-11-12T19:03:01.087Z,1605207781.087 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20201112T185118/Express0007.lzma
2020-11-12T19:03:02.089Z,1605207782.089 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0007.lzma.bak
2020-11-12T19:03:02.089Z,1605207782.089 [DataOverHttps](INFO): SBD MOMSN=13194923
2020-11-12T19:03:03.594Z,1605207783.594 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:03:03.594Z,1605207783.594 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:03:03.594Z,1605207783.594 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:03:08.780Z,1605207788.780 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:04:48.684Z,1605207888.684 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-12T19:08:04.225Z,1605208084.225 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:08:04.225Z,1605208084.225 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:08:04.225Z,1605208084.225 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:08:04.226Z,1605208084.226 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:08:04.635Z,1605208084.635 [Default:CheckIn:D] Stopped
2020-11-12T19:08:04.635Z,1605208084.635 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:08:04.997Z,1605208084.997 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.300094 min 
2020-11-12T19:08:04.997Z,1605208084.997 [Default:CheckIn:E] Stopped
2020-11-12T19:08:04.997Z,1605208084.997 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:08:04.997Z,1605208084.997 [Default:CheckIn] Stopped
2020-11-12T19:08:04.997Z,1605208084.997 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:08:04.998Z,1605208084.998 [Default:CheckIn](INFO): Running loop #4
2020-11-12T19:08:04.998Z,1605208084.998 [Default:CheckIn] Running Loop=4
2020-11-12T19:08:04.998Z,1605208084.998 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:08:04.998Z,1605208084.998 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:08:07.018Z,1605208087.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190805.00,A,3648.16586,N,12147.21485,W,0.058,19.78,121120,,,D*49
2020-11-12T19:08:07.020Z,1605208087.020 [NAL9602](INFO): GPS fix at 20201112T190805: (36.802764, -121.786914)
2020-11-12T19:08:07.031Z,1605208087.031 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:08:07.031Z,1605208087.031 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:08:15.130Z,1605208095.130 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0009.lzma
2020-11-12T19:08:16.133Z,1605208096.133 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0009.lzma.bak
2020-11-12T19:08:16.133Z,1605208096.133 [DataOverHttps](INFO): SBD MOMSN=13195020
2020-11-12T19:08:31.325Z,1605208111.325 [NAL9602](INFO): SBD MO Status=2, MOMSN=11376, MT Status=2, MTMSN=0
2020-11-12T19:08:31.326Z,1605208111.326 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:08:38.679Z,1605208118.679 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20201112T185118/Express0010.lzma
2020-11-12T19:08:39.681Z,1605208119.681 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0010.lzma.bak
2020-11-12T19:08:39.681Z,1605208119.681 [DataOverHttps](INFO): SBD MOMSN=13195026
2020-11-12T19:08:41.094Z,1605208121.094 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:08:41.095Z,1605208121.095 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:08:41.123Z,1605208121.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:08:57.233Z,1605208137.233 [NAL9602](INFO): SBD MO Status=2, MOMSN=11376, MT Status=2, MTMSN=0
2020-11-12T19:08:57.234Z,1605208137.234 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:09:20.670Z,1605208160.670 [NAL9602](INFO): SBD MO Status=2, MOMSN=11376, MT Status=2, MTMSN=0
2020-11-12T19:09:20.670Z,1605208160.670 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:10:20.053Z,1605208220.053 [NAL9602](INFO): SBD MO Status=2, MOMSN=11376, MT Status=2, MTMSN=0
2020-11-12T19:10:20.054Z,1605208220.054 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:10:31.802Z,1605208231.802 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=11376, MT Status=1, MTMSN=631
2020-11-12T19:10:31.802Z,1605208231.802 [NAL9602](INFO): Data available in MT queue
2020-11-12T19:10:32.312Z,1605208232.312 [NAL9602](INFO): Received command:stop
2020-11-12T19:10:32.324Z,1605208232.324 [CommandLine](IMPORTANT): got command stop
2020-11-12T19:11:02.946Z,1605208262.946 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:13:41.825Z,1605208421.825 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:13:41.825Z,1605208421.825 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:13:41.825Z,1605208421.825 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:13:41.826Z,1605208421.826 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:13:42.196Z,1605208422.196 [Default:CheckIn:D] Stopped
2020-11-12T19:13:42.196Z,1605208422.196 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:13:42.613Z,1605208422.613 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.926115 min 
2020-11-12T19:13:42.613Z,1605208422.613 [Default:CheckIn:E] Stopped
2020-11-12T19:13:42.613Z,1605208422.613 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:13:42.613Z,1605208422.613 [Default:CheckIn] Stopped
2020-11-12T19:13:42.614Z,1605208422.614 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:13:42.614Z,1605208422.614 [Default:CheckIn](INFO): Running loop #5
2020-11-12T19:13:42.614Z,1605208422.614 [Default:CheckIn] Running Loop=5
2020-11-12T19:13:42.614Z,1605208422.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:13:42.614Z,1605208422.614 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:13:44.609Z,1605208424.609 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191343.00,A,3648.14726,N,12147.22103,W,0.097,183.96,121120,,,D*72
2020-11-12T19:13:44.612Z,1605208424.612 [NAL9602](INFO): GPS fix at 20201112T191343: (36.802454, -121.787017)
2020-11-12T19:13:44.623Z,1605208424.623 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:13:44.623Z,1605208424.623 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:13:52.718Z,1605208432.718 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0012.lzma
2020-11-12T19:13:53.721Z,1605208433.721 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0012.lzma.bak
2020-11-12T19:13:53.721Z,1605208433.721 [DataOverHttps](INFO): SBD MOMSN=13195038
2020-11-12T19:14:07.238Z,1605208447.238 [NAL9602](INFO): SBD MO Status=2, MOMSN=11377, MT Status=2, MTMSN=0
2020-11-12T19:14:07.238Z,1605208447.238 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:14:10.127Z,1605208450.127 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20201112T185118/Express0013.lzma
2020-11-12T19:14:11.129Z,1605208451.129 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0013.lzma.bak
2020-11-12T19:14:11.129Z,1605208451.129 [DataOverHttps](INFO): SBD MOMSN=13195041
2020-11-12T19:14:12.506Z,1605208452.506 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:14:12.506Z,1605208452.506 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:14:12.506Z,1605208452.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:14:23.016Z,1605208463.016 [NAL9602](INFO): SBD MO Status=2, MOMSN=11377, MT Status=2, MTMSN=0
2020-11-12T19:14:23.016Z,1605208463.016 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:15:22.800Z,1605208522.800 [NAL9602](INFO): SBD MO Status=0, MOMSN=11377, MT Status=0, MTMSN=0
2020-11-12T19:15:22.800Z,1605208522.800 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:15:53.504Z,1605208553.504 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:19:12.352Z,1605208752.352 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2020-11-12T19:19:12.355Z,1605208752.355 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T19:19:13.191Z,1605208753.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:19:13.192Z,1605208753.192 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:19:13.192Z,1605208753.192 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:19:13.192Z,1605208753.192 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:19:13.566Z,1605208753.566 [Default:CheckIn:D] Stopped
2020-11-12T19:19:13.566Z,1605208753.566 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:19:13.977Z,1605208753.977 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.448956 min 
2020-11-12T19:19:13.977Z,1605208753.977 [Default:CheckIn:E] Stopped
2020-11-12T19:19:13.977Z,1605208753.977 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:19:13.977Z,1605208753.977 [Default:CheckIn] Stopped
2020-11-12T19:19:13.977Z,1605208753.977 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:19:13.978Z,1605208753.978 [Default:CheckIn](INFO): Running loop #6
2020-11-12T19:19:13.978Z,1605208753.978 [Default:CheckIn] Running Loop=6
2020-11-12T19:19:13.978Z,1605208753.978 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:19:13.978Z,1605208753.978 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:19:15.978Z,1605208755.978 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191915.00,A,3648.17315,N,12147.27852,W,0.136,288.94,121120,,,A*71
2020-11-12T19:19:15.980Z,1605208755.980 [NAL9602](INFO): GPS fix at 20201112T191915: (36.802886, -121.787975)
2020-11-12T19:19:16.012Z,1605208756.012 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:19:16.012Z,1605208756.012 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:19:23.963Z,1605208763.963 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20201112T185118/Courier0015.lzma
2020-11-12T19:19:24.965Z,1605208764.965 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0015.lzma.bak
2020-11-12T19:19:24.965Z,1605208764.965 [DataOverHttps](INFO): SBD MOMSN=13195147
2020-11-12T19:19:41.339Z,1605208781.339 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20201112T185118/Express0016.lzma
2020-11-12T19:19:42.341Z,1605208782.341 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0016.lzma.bak
2020-11-12T19:19:42.341Z,1605208782.341 [DataOverHttps](INFO): SBD MOMSN=13195150
2020-11-12T19:19:43.531Z,1605208783.531 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:19:43.531Z,1605208783.531 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:19:43.531Z,1605208783.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:20:24.698Z,1605208824.698 [NAL9602](INFO): SBD MO Status=2, MOMSN=11378, MT Status=2, MTMSN=0
2020-11-12T19:20:24.698Z,1605208824.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:20:38.063Z,1605208838.063 [NAL9602](INFO): SBD MO Status=2, MOMSN=11378, MT Status=2, MTMSN=0
2020-11-12T19:20:38.063Z,1605208838.063 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:20:52.601Z,1605208852.601 [NAL9602](INFO): SBD MO Status=2, MOMSN=11378, MT Status=2, MTMSN=0
2020-11-12T19:20:52.602Z,1605208852.602 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:22:32.809Z,1605208952.809 [NAL9602](INFO): SBD MO Status=2, MOMSN=11378, MT Status=2, MTMSN=0
2020-11-12T19:22:32.810Z,1605208952.810 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:22:51.789Z,1605208971.789 [NAL9602](INFO): SBD MO Status=0, MOMSN=11378, MT Status=0, MTMSN=0
2020-11-12T19:22:51.790Z,1605208971.790 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:23:22.529Z,1605209002.529 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:24:44.146Z,1605209084.146 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:24:44.146Z,1605209084.146 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:24:44.146Z,1605209084.146 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:24:44.147Z,1605209084.147 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:24:44.550Z,1605209084.550 [Default:CheckIn:D] Stopped
2020-11-12T19:24:44.550Z,1605209084.550 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:24:44.967Z,1605209084.967 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.965356 min 
2020-11-12T19:24:44.967Z,1605209084.967 [Default:CheckIn:E] Stopped
2020-11-12T19:24:44.967Z,1605209084.967 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn] Stopped
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn](INFO): Running loop #7
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn] Running Loop=7
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:24:44.968Z,1605209084.968 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:24:46.962Z,1605209086.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192446.00,A,3648.17227,N,12147.27990,W,0.583,41.01,121120,,,A*47
2020-11-12T19:24:46.964Z,1605209086.964 [NAL9602](INFO): GPS fix at 20201112T192446: (36.802871, -121.787998)
2020-11-12T19:24:46.978Z,1605209086.978 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:24:46.979Z,1605209086.979 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:24:54.274Z,1605209094.274 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0018.lzma
2020-11-12T19:24:55.277Z,1605209095.277 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0018.lzma.bak
2020-11-12T19:24:55.277Z,1605209095.277 [DataOverHttps](INFO): SBD MOMSN=13195161
2020-11-12T19:25:11.607Z,1605209111.607 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201112T185118/Express0019.lzma
2020-11-12T19:25:12.609Z,1605209112.609 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0019.lzma.bak
2020-11-12T19:25:12.609Z,1605209112.609 [DataOverHttps](INFO): SBD MOMSN=13195164
2020-11-12T19:25:14.085Z,1605209114.085 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:25:14.086Z,1605209114.086 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:25:14.086Z,1605209114.086 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:29:49.660Z,1605209389.660 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:30:14.722Z,1605209414.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:30:14.722Z,1605209414.722 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:30:14.722Z,1605209414.722 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:30:14.723Z,1605209414.723 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:30:15.136Z,1605209415.136 [Default:CheckIn:D] Stopped
2020-11-12T19:30:15.136Z,1605209415.136 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.475118 min 
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn:E] Stopped
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn] Stopped
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn](INFO): Running loop #8
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn] Running Loop=8
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:30:15.528Z,1605209415.528 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:30:17.538Z,1605209417.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193016.00,A,3648.16705,N,12147.27958,W,0.136,41.01,121120,,,A*4D
2020-11-12T19:30:17.540Z,1605209417.540 [NAL9602](INFO): GPS fix at 20201112T193016: (36.802784, -121.787993)
2020-11-12T19:30:17.605Z,1605209417.605 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:30:17.605Z,1605209417.605 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:30:28.195Z,1605209428.195 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T185118/Courier0021.lzma
2020-11-12T19:30:29.197Z,1605209429.197 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0021.lzma.bak
2020-11-12T19:30:29.197Z,1605209429.197 [DataOverHttps](INFO): SBD MOMSN=13195264
2020-11-12T19:30:48.795Z,1605209448.795 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20201112T185118/Express0022.lzma
2020-11-12T19:30:49.170Z,1605209449.170 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:30:49.797Z,1605209449.797 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0022.lzma.bak
2020-11-12T19:30:49.797Z,1605209449.797 [DataOverHttps](INFO): SBD MOMSN=13195267
2020-11-12T19:30:51.220Z,1605209451.220 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:30:51.221Z,1605209451.221 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:30:51.221Z,1605209451.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:35:51.857Z,1605209751.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:35:51.857Z,1605209751.857 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:35:51.857Z,1605209751.857 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:35:51.857Z,1605209751.857 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:35:52.256Z,1605209752.256 [Default:CheckIn:D] Stopped
2020-11-12T19:35:52.256Z,1605209752.256 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.093787 min 
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn:E] Stopped
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn] Stopped
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn](INFO): Running loop #9
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn] Running Loop=9
2020-11-12T19:35:52.672Z,1605209752.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:35:52.673Z,1605209752.673 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:35:54.670Z,1605209754.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193553.00,A,3648.16733,N,12147.28089,W,0.078,148.21,121120,,,D*72
2020-11-12T19:35:54.672Z,1605209754.672 [NAL9602](INFO): GPS fix at 20201112T193553: (36.802789, -121.788015)
2020-11-12T19:35:54.710Z,1605209754.710 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:35:54.710Z,1605209754.710 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:36:02.730Z,1605209762.730 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0024.lzma
2020-11-12T19:36:03.733Z,1605209763.733 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0024.lzma.bak
2020-11-12T19:36:03.734Z,1605209763.734 [DataOverHttps](INFO): SBD MOMSN=13195279
2020-11-12T19:36:22.183Z,1605209782.183 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201112T185118/Express0025.lzma
2020-11-12T19:36:23.185Z,1605209783.185 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0025.lzma.bak
2020-11-12T19:36:23.185Z,1605209783.185 [DataOverHttps](INFO): SBD MOMSN=13195282
2020-11-12T19:36:24.596Z,1605209784.596 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:36:24.596Z,1605209784.596 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:36:24.596Z,1605209784.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:36:25.387Z,1605209785.387 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T19:36:25.468Z,1605209785.468 [NAL9602](FAULT): received: 
+CSQ:0

OK378, 0, 0, 0, 0

OK
2020-11-12T19:36:25.468Z,1605209785.468 [NAL9602] Data Fault, FailCount= 1
2020-11-12T19:36:25.468Z,1605209785.468 [NAL9602](ERROR): Data Fault
2020-11-12T19:36:25.603Z,1605209785.603 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T19:36:25.792Z,1605209785.792 [NAL9602](INFO): Powering down
2020-11-12T19:36:26.735Z,1605209786.735 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T19:36:26.735Z,1605209786.735 [NAL9602] No Fault, FailCount= 1
2020-11-12T19:36:56.154Z,1605209816.154 [NAL9602](INFO): Powering up NAL9602
2020-11-12T19:37:07.064Z,1605209827.064 [NAL9602](INFO): NAL9602 initialized
2020-11-12T19:37:38.172Z,1605209858.172 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:41:25.234Z,1605210085.234 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:41:25.234Z,1605210085.234 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:41:25.235Z,1605210085.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:41:25.235Z,1605210085.235 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:41:25.649Z,1605210085.649 [Default:CheckIn:D] Stopped
2020-11-12T19:41:25.649Z,1605210085.649 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:41:26.050Z,1605210086.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.650334 min 
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn:E] Stopped
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn] Stopped
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn](INFO): Running loop #10
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn] Running Loop=10
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:41:26.051Z,1605210086.051 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:41:28.054Z,1605210088.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194126.00,A,3648.17421,N,12147.28241,W,0.233,214.71,121120,,,D*76
2020-11-12T19:41:28.056Z,1605210088.056 [NAL9602](INFO): GPS fix at 20201112T194126: (36.802903, -121.788040)
2020-11-12T19:41:28.067Z,1605210088.067 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:41:28.087Z,1605210088.087 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:41:30.096Z,1605210090.096 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:41:35.350Z,1605210095.350 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0027.lzma
2020-11-12T19:41:36.353Z,1605210096.353 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0027.lzma.bak
2020-11-12T19:41:36.353Z,1605210096.353 [DataOverHttps](INFO): SBD MOMSN=13195378
2020-11-12T19:41:52.802Z,1605210112.802 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20201112T185118/Express0028.lzma
2020-11-12T19:41:53.801Z,1605210113.801 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0028.lzma.bak
2020-11-12T19:41:53.801Z,1605210113.801 [DataOverHttps](INFO): SBD MOMSN=13195381
2020-11-12T19:41:55.167Z,1605210115.167 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:41:55.168Z,1605210115.168 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:41:55.168Z,1605210115.168 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:42:00.800Z,1605210120.800 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:46:55.902Z,1605210415.902 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:46:55.902Z,1605210415.902 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:46:55.902Z,1605210415.902 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:46:55.902Z,1605210415.902 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:46:56.233Z,1605210416.233 [Default:CheckIn:D] Stopped
2020-11-12T19:46:56.233Z,1605210416.233 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.160059 min 
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn:E] Stopped
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn] Stopped
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:46:56.686Z,1605210416.686 [Default:CheckIn](INFO): Running loop #11
2020-11-12T19:46:56.687Z,1605210416.687 [Default:CheckIn] Running Loop=11
2020-11-12T19:46:56.687Z,1605210416.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:46:56.687Z,1605210416.687 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:46:58.664Z,1605210418.664 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194657.00,A,3648.17044,N,12147.28262,W,0.525,214.71,121120,,,A*74
2020-11-12T19:46:58.666Z,1605210418.666 [NAL9602](INFO): GPS fix at 20201112T194657: (36.802841, -121.788044)
2020-11-12T19:46:58.677Z,1605210418.677 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:46:58.677Z,1605210418.677 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:47:06.678Z,1605210426.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0030.lzma
2020-11-12T19:47:07.681Z,1605210427.681 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0030.lzma.bak
2020-11-12T19:47:07.681Z,1605210427.681 [DataOverHttps](INFO): SBD MOMSN=13195392
2020-11-12T19:47:24.051Z,1605210444.051 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20201112T185118/Express0031.lzma
2020-11-12T19:47:25.053Z,1605210445.053 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0031.lzma.bak
2020-11-12T19:47:25.053Z,1605210445.053 [DataOverHttps](INFO): SBD MOMSN=13195395
2020-11-12T19:47:26.546Z,1605210446.546 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:47:26.546Z,1605210446.546 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:47:26.546Z,1605210446.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:47:56.826Z,1605210476.826 [NAL9602](INFO): SBD MO Status=2, MOMSN=11379, MT Status=2, MTMSN=0
2020-11-12T19:47:56.826Z,1605210476.826 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:48:10.981Z,1605210490.981 [NAL9602](INFO): SBD MO Status=2, MOMSN=11379, MT Status=2, MTMSN=0
2020-11-12T19:48:10.982Z,1605210490.982 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:48:28.341Z,1605210508.341 [NAL9602](INFO): SBD MO Status=2, MOMSN=11379, MT Status=2, MTMSN=0
2020-11-12T19:48:28.341Z,1605210508.341 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:48:54.193Z,1605210534.193 [NAL9602](INFO): SBD MO Status=2, MOMSN=11379, MT Status=2, MTMSN=0
2020-11-12T19:48:54.194Z,1605210534.194 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:49:08.337Z,1605210548.337 [NAL9602](INFO): SBD MO Status=0, MOMSN=11379, MT Status=0, MTMSN=0
2020-11-12T19:49:08.337Z,1605210548.337 [NAL9602](INFO): No messages in MT queue
2020-11-12T19:49:39.040Z,1605210579.040 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:52:27.188Z,1605210747.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:52:27.188Z,1605210747.188 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:52:27.188Z,1605210747.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:52:27.189Z,1605210747.189 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:52:27.684Z,1605210747.684 [Default:CheckIn:D] Stopped
2020-11-12T19:52:27.684Z,1605210747.684 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:52:28.035Z,1605210748.035 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.684249 min 
2020-11-12T19:52:28.035Z,1605210748.035 [Default:CheckIn:E] Stopped
2020-11-12T19:52:28.035Z,1605210748.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:52:28.035Z,1605210748.035 [Default:CheckIn] Stopped
2020-11-12T19:52:28.035Z,1605210748.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:52:28.036Z,1605210748.036 [Default:CheckIn](INFO): Running loop #12
2020-11-12T19:52:28.036Z,1605210748.036 [Default:CheckIn] Running Loop=12
2020-11-12T19:52:28.036Z,1605210748.036 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:52:28.036Z,1605210748.036 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:52:30.006Z,1605210750.006 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195229.00,A,3648.16930,N,12147.27803,W,0.661,214.71,121120,,,A*72
2020-11-12T19:52:30.008Z,1605210750.008 [NAL9602](INFO): GPS fix at 20201112T195229: (36.802822, -121.787967)
2020-11-12T19:52:30.042Z,1605210750.042 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:52:30.042Z,1605210750.042 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:52:40.678Z,1605210760.678 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0033.lzma
2020-11-12T19:52:41.684Z,1605210761.684 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0033.lzma.bak
2020-11-12T19:52:41.684Z,1605210761.684 [DataOverHttps](INFO): SBD MOMSN=13195503
2020-11-12T19:52:57.999Z,1605210777.999 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201112T185118/Express0034.lzma
2020-11-12T19:52:59.001Z,1605210779.001 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0034.lzma.bak
2020-11-12T19:52:59.001Z,1605210779.001 [DataOverHttps](INFO): SBD MOMSN=13195506
2020-11-12T19:53:00.359Z,1605210780.359 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:53:00.359Z,1605210780.359 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:53:00.360Z,1605210780.360 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:55:59.718Z,1605210959.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-11-12T19:55:59.718Z,1605210959.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:56:57.902Z,1605211017.902 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-11-12T19:56:57.902Z,1605211017.902 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:57:10.829Z,1605211030.829 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-11-12T19:57:10.830Z,1605211030.830 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T19:57:32.237Z,1605211052.237 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T19:58:00.930Z,1605211080.930 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T19:58:00.930Z,1605211080.930 [Default:CheckIn:C.Wait] Stopped
2020-11-12T19:58:00.930Z,1605211080.930 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T19:58:00.931Z,1605211080.931 [Default:CheckIn:D] Running Loop=1
2020-11-12T19:58:01.342Z,1605211081.342 [Default:CheckIn:D] Stopped
2020-11-12T19:58:01.342Z,1605211081.342 [Default:CheckIn:E] Running Loop=1
2020-11-12T19:58:01.761Z,1605211081.761 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 71.245215 min 
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn:E] Stopped
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn] Stopped
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn](INFO): Running loop #13
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn] Running Loop=13
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T19:58:01.762Z,1605211081.762 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T19:58:03.751Z,1605211083.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195803.00,A,3648.16537,N,12147.27907,W,0.641,0.00,121120,,,A*7D
2020-11-12T19:58:03.753Z,1605211083.753 [NAL9602](INFO): GPS fix at 20201112T195803: (36.802756, -121.787984)
2020-11-12T19:58:03.775Z,1605211083.775 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T19:58:03.775Z,1605211083.775 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T19:58:11.070Z,1605211091.070 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0036.lzma
2020-11-12T19:58:12.073Z,1605211092.073 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0036.lzma.bak
2020-11-12T19:58:12.073Z,1605211092.073 [DataOverHttps](INFO): SBD MOMSN=13195520
2020-11-12T19:58:36.220Z,1605211116.220 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T19:58:39.843Z,1605211119.843 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20201112T185118/Express0037.lzma
2020-11-12T19:58:40.845Z,1605211120.845 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0037.lzma.bak
2020-11-12T19:58:40.845Z,1605211120.845 [DataOverHttps](INFO): SBD MOMSN=13195523
2020-11-12T19:58:42.312Z,1605211122.312 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T19:58:42.312Z,1605211122.312 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T19:58:42.312Z,1605211122.312 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T19:59:16.643Z,1605211156.643 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-12T20:03:42.986Z,1605211422.986 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:03:42.986Z,1605211422.986 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:03:42.986Z,1605211422.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:03:42.987Z,1605211422.987 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:03:43.391Z,1605211423.391 [Default:CheckIn:D] Stopped
2020-11-12T20:03:43.391Z,1605211423.391 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.946029 min 
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn:E] Stopped
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn] Stopped
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn](INFO): Running loop #14
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn] Running Loop=14
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:03:43.809Z,1605211423.809 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:03:45.806Z,1605211425.806 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200345.00,A,3648.16976,N,12147.28190,W,0.058,0.00,121120,,,A*75
2020-11-12T20:03:45.808Z,1605211425.808 [NAL9602](INFO): GPS fix at 20201112T200345: (36.802829, -121.788032)
2020-11-12T20:03:45.862Z,1605211425.862 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:03:45.862Z,1605211425.862 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:03:53.642Z,1605211433.642 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0039.lzma
2020-11-12T20:03:54.645Z,1605211434.645 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0039.lzma.bak
2020-11-12T20:03:54.645Z,1605211434.645 [DataOverHttps](INFO): SBD MOMSN=13195619
2020-11-12T20:04:11.003Z,1605211451.003 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20201112T185118/Express0040.lzma
2020-11-12T20:04:12.005Z,1605211452.005 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0040.lzma.bak
2020-11-12T20:04:12.005Z,1605211452.005 [DataOverHttps](INFO): SBD MOMSN=13195622
2020-11-12T20:04:13.291Z,1605211453.291 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:04:13.291Z,1605211453.291 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:04:13.292Z,1605211453.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:04:16.508Z,1605211456.508 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T20:04:16.587Z,1605211456.587 [NAL9602](FAULT): received: 
+CSQ:0

OK380, 2, 0, 0, 0

OK
2020-11-12T20:04:16.587Z,1605211456.587 [NAL9602] Data Fault, FailCount= 1
2020-11-12T20:04:16.587Z,1605211456.587 [NAL9602](ERROR): Data Fault
2020-11-12T20:04:16.664Z,1605211456.664 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T20:04:16.908Z,1605211456.908 [NAL9602](INFO): Powering down
2020-11-12T20:04:17.800Z,1605211457.800 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T20:04:17.800Z,1605211457.800 [NAL9602] No Fault, FailCount= 1
2020-11-12T20:04:47.248Z,1605211487.248 [NAL9602](INFO): Powering up NAL9602
2020-11-12T20:04:58.152Z,1605211498.152 [NAL9602](INFO): NAL9602 initialized
2020-11-12T20:05:29.272Z,1605211529.272 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:09:14.024Z,1605211754.024 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:09:14.025Z,1605211754.025 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:09:14.025Z,1605211754.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:09:14.025Z,1605211754.025 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:09:14.413Z,1605211754.413 [Default:CheckIn:D] Stopped
2020-11-12T20:09:14.414Z,1605211754.414 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.463078 min 
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn:E] Stopped
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn] Stopped
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn](INFO): Running loop #15
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn] Running Loop=15
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:09:14.824Z,1605211754.824 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:09:16.820Z,1605211756.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200915.00,A,3648.17056,N,12147.27780,W,0.117,0.00,121120,,,A*72
2020-11-12T20:09:16.822Z,1605211756.822 [NAL9602](INFO): GPS fix at 20201112T200915: (36.802843, -121.787963)
2020-11-12T20:09:16.844Z,1605211756.844 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:09:16.844Z,1605211756.844 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:09:19.251Z,1605211759.251 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:09:24.385Z,1605211764.385 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T185118/Courier0042.lzma
2020-11-12T20:09:25.385Z,1605211765.385 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0042.lzma.bak
2020-11-12T20:09:25.385Z,1605211765.385 [DataOverHttps](INFO): SBD MOMSN=13195632
2020-11-12T20:09:41.791Z,1605211781.791 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20201112T185118/Express0043.lzma
2020-11-12T20:09:42.793Z,1605211782.793 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0043.lzma.bak
2020-11-12T20:09:42.793Z,1605211782.793 [DataOverHttps](INFO): SBD MOMSN=13195637
2020-11-12T20:09:43.917Z,1605211783.917 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:09:43.917Z,1605211783.917 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:09:43.917Z,1605211783.917 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:09:49.954Z,1605211789.954 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:14:44.637Z,1605212084.637 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:14:44.637Z,1605212084.637 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:14:44.637Z,1605212084.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:14:44.637Z,1605212084.637 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:14:45.034Z,1605212085.034 [Default:CheckIn:D] Stopped
2020-11-12T20:14:45.034Z,1605212085.034 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:14:45.456Z,1605212085.456 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.973421 min 
2020-11-12T20:14:45.456Z,1605212085.456 [Default:CheckIn:E] Stopped
2020-11-12T20:14:45.457Z,1605212085.457 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:14:45.457Z,1605212085.457 [Default:CheckIn] Stopped
2020-11-12T20:14:45.457Z,1605212085.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:14:45.458Z,1605212085.458 [Default:CheckIn](INFO): Running loop #16
2020-11-12T20:14:45.458Z,1605212085.458 [Default:CheckIn] Running Loop=16
2020-11-12T20:14:45.458Z,1605212085.458 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:14:45.458Z,1605212085.458 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:14:47.472Z,1605212087.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201446.00,A,3648.16714,N,12147.28281,W,0.194,0.00,121120,,,A*78
2020-11-12T20:14:47.484Z,1605212087.484 [NAL9602](INFO): GPS fix at 20201112T201446: (36.802786, -121.788047)
2020-11-12T20:14:47.518Z,1605212087.518 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:14:47.518Z,1605212087.518 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:14:54.694Z,1605212094.694 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0045.lzma
2020-11-12T20:14:55.698Z,1605212095.698 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0045.lzma.bak
2020-11-12T20:14:55.698Z,1605212095.698 [DataOverHttps](INFO): SBD MOMSN=13195742
2020-11-12T20:15:12.247Z,1605212112.247 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20201112T185118/Express0046.lzma
2020-11-12T20:15:13.249Z,1605212113.249 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0046.lzma.bak
2020-11-12T20:15:13.249Z,1605212113.249 [DataOverHttps](INFO): SBD MOMSN=13195745
2020-11-12T20:15:14.557Z,1605212114.557 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:15:14.557Z,1605212114.557 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:15:14.557Z,1605212114.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:15:37.137Z,1605212137.137 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-11-12T20:15:37.138Z,1605212137.138 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:18:26.102Z,1605212306.102 [NAL9602](INFO): SBD MO Status=2, MOMSN=11380, MT Status=2, MTMSN=0
2020-11-12T20:18:26.102Z,1605212306.102 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T20:19:07.750Z,1605212347.750 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2020-11-12T20:19:49.332Z,1605212389.332 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:20:15.257Z,1605212415.257 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:20:15.257Z,1605212415.257 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:20:15.258Z,1605212415.258 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:20:15.258Z,1605212415.258 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:20:15.615Z,1605212415.615 [Default:CheckIn:D] Stopped
2020-11-12T20:20:15.615Z,1605212415.615 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:20:16.050Z,1605212416.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.483105 min 
2020-11-12T20:20:16.050Z,1605212416.050 [Default:CheckIn:E] Stopped
2020-11-12T20:20:16.050Z,1605212416.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:20:16.050Z,1605212416.050 [Default:CheckIn] Stopped
2020-11-12T20:20:16.051Z,1605212416.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:20:16.051Z,1605212416.051 [Default:CheckIn](INFO): Running loop #17
2020-11-12T20:20:16.051Z,1605212416.051 [Default:CheckIn] Running Loop=17
2020-11-12T20:20:16.051Z,1605212416.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:20:16.051Z,1605212416.051 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:20:18.017Z,1605212418.017 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202017.00,A,3648.16882,N,12147.28088,W,0.019,0.00,121120,,,A*74
2020-11-12T20:20:18.019Z,1605212418.019 [NAL9602](INFO): GPS fix at 20201112T202017: (36.802814, -121.788015)
2020-11-12T20:20:18.040Z,1605212418.040 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:20:18.040Z,1605212418.040 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:20:29.170Z,1605212429.170 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0048.lzma
2020-11-12T20:20:30.169Z,1605212430.169 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0048.lzma.bak
2020-11-12T20:20:30.169Z,1605212430.169 [DataOverHttps](INFO): SBD MOMSN=13195832
2020-11-12T20:20:50.163Z,1605212450.163 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:20:50.599Z,1605212450.599 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20201112T185118/Express0049.lzma
2020-11-12T20:20:51.601Z,1605212451.601 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0049.lzma.bak
2020-11-12T20:20:51.601Z,1605212451.601 [DataOverHttps](INFO): SBD MOMSN=13195844
2020-11-12T20:20:52.999Z,1605212452.999 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:20:52.000Z,1605212453.000 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:20:52.000Z,1605212453.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:25:53.680Z,1605212753.680 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:25:53.680Z,1605212753.680 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:25:53.680Z,1605212753.680 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:25:53.680Z,1605212753.680 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:25:54.047Z,1605212754.047 [Default:CheckIn:D] Stopped
2020-11-12T20:25:54.047Z,1605212754.047 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:25:54.447Z,1605212754.447 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.123625 min 
2020-11-12T20:25:54.447Z,1605212754.447 [Default:CheckIn:E] Stopped
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn] Stopped
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn](INFO): Running loop #18
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn] Running Loop=18
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:25:54.448Z,1605212754.448 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:25:56.462Z,1605212756.462 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202555.00,A,3648.16891,N,12147.28621,W,0.214,206.14,121120,,,A*7E
2020-11-12T20:25:56.474Z,1605212756.474 [NAL9602](INFO): GPS fix at 20201112T202555: (36.802815, -121.788104)
2020-11-12T20:25:56.554Z,1605212756.554 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:25:56.554Z,1605212756.554 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:26:04.106Z,1605212764.106 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0051.lzma
2020-11-12T20:26:05.115Z,1605212765.115 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0051.lzma.bak
2020-11-12T20:26:05.115Z,1605212765.115 [DataOverHttps](INFO): SBD MOMSN=13195958
2020-11-12T20:26:25.343Z,1605212785.343 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201112T185118/Express0052.lzma
2020-11-12T20:26:26.345Z,1605212786.345 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0052.lzma.bak
2020-11-12T20:26:26.345Z,1605212786.345 [DataOverHttps](INFO): SBD MOMSN=13195961
2020-11-12T20:26:27.244Z,1605212787.244 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T20:26:27.319Z,1605212787.319 [NAL9602](FAULT): received: 
+CSQ:0

OK380, 2, 0, 0, 0

OK
2020-11-12T20:26:27.320Z,1605212787.320 [NAL9602] Data Fault, FailCount= 1
2020-11-12T20:26:27.320Z,1605212787.320 [NAL9602](ERROR): Data Fault
2020-11-12T20:26:27.476Z,1605212787.476 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T20:26:27.648Z,1605212787.648 [NAL9602](INFO): Powering down
2020-11-12T20:26:27.736Z,1605212787.736 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:26:27.736Z,1605212787.736 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:26:27.736Z,1605212787.736 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:26:28.544Z,1605212788.544 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T20:26:28.544Z,1605212788.544 [NAL9602] No Fault, FailCount= 1
2020-11-12T20:26:39.888Z,1605212799.888 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-11-12T20:26:39.890Z,1605212799.890 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T20:26:58.014Z,1605212818.014 [NAL9602](INFO): Powering up NAL9602
2020-11-12T20:27:08.916Z,1605212828.916 [NAL9602](INFO): NAL9602 initialized
2020-11-12T20:27:40.029Z,1605212860.029 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:28:58.064Z,1605212938.064 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-12T20:31:28.366Z,1605213088.366 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:31:28.366Z,1605213088.366 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:31:28.366Z,1605213088.366 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:31:28.366Z,1605213088.366 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:31:28.777Z,1605213088.777 [Default:CheckIn:D] Stopped
2020-11-12T20:31:28.777Z,1605213088.777 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.702466 min 
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn:E] Stopped
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn] Stopped
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:31:29.182Z,1605213089.182 [Default:CheckIn](INFO): Running loop #19
2020-11-12T20:31:29.183Z,1605213089.183 [Default:CheckIn] Running Loop=19
2020-11-12T20:31:29.183Z,1605213089.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:31:29.183Z,1605213089.183 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:31:31.182Z,1605213091.182 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203129.00,A,3648.16865,N,12147.28034,W,0.136,0.00,121120,,,A*7B
2020-11-12T20:31:31.184Z,1605213091.184 [NAL9602](INFO): GPS fix at 20201112T203129: (36.802811, -121.788006)
2020-11-12T20:31:31.234Z,1605213091.234 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:31:31.235Z,1605213091.235 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:31:33.216Z,1605213093.216 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:31:56.462Z,1605213116.462 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20201112T185118/Courier0054.lzma
2020-11-12T20:31:57.465Z,1605213117.465 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0054.lzma.bak
2020-11-12T20:31:57.465Z,1605213117.465 [DataOverHttps](INFO): SBD MOMSN=13196021
2020-11-12T20:32:03.904Z,1605213123.904 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:32:13.664Z,1605213133.664 [DataOverHttps](INFO): Sending 173 bytes from file Logs/20201112T185118/Express0055.lzma
2020-11-12T20:32:14.657Z,1605213134.657 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0055.lzma.bak
2020-11-12T20:32:14.657Z,1605213134.657 [DataOverHttps](INFO): SBD MOMSN=13196024
2020-11-12T20:32:16.077Z,1605213136.077 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:32:16.077Z,1605213136.077 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:32:16.078Z,1605213136.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:37:16.773Z,1605213436.773 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:37:16.773Z,1605213436.773 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:37:16.773Z,1605213436.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:37:16.773Z,1605213436.773 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:37:17.150Z,1605213437.150 [Default:CheckIn:D] Stopped
2020-11-12T20:37:17.150Z,1605213437.150 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:37:17.560Z,1605213437.560 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.508691 min 
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn:E] Stopped
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn] Stopped
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn](INFO): Running loop #20
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn] Running Loop=20
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:37:17.561Z,1605213437.561 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:37:19.566Z,1605213439.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203718.00,A,3648.16942,N,12147.28097,W,0.369,0.00,121120,,,A*7A
2020-11-12T20:37:19.568Z,1605213439.568 [NAL9602](INFO): GPS fix at 20201112T203718: (36.802824, -121.788016)
2020-11-12T20:37:19.595Z,1605213439.595 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:37:19.595Z,1605213439.595 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:37:27.362Z,1605213447.362 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0057.lzma
2020-11-12T20:37:28.365Z,1605213448.365 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0057.lzma.bak
2020-11-12T20:37:28.365Z,1605213448.365 [DataOverHttps](INFO): SBD MOMSN=13196159
2020-11-12T20:37:44.735Z,1605213464.735 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20201112T185118/Express0058.lzma
2020-11-12T20:37:45.737Z,1605213465.737 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0058.lzma.bak
2020-11-12T20:37:45.737Z,1605213465.737 [DataOverHttps](INFO): SBD MOMSN=13196162
2020-11-12T20:37:47.059Z,1605213467.059 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:37:47.059Z,1605213467.059 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:37:47.059Z,1605213467.059 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:39:26.496Z,1605213566.496 [NAL9602](INFO): SBD MO Status=0, MOMSN=11380, MT Status=0, MTMSN=0
2020-11-12T20:39:26.496Z,1605213566.496 [NAL9602](INFO): No messages in MT queue
2020-11-12T20:39:57.189Z,1605213597.189 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:42:47.701Z,1605213767.701 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:42:47.701Z,1605213767.701 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:42:47.701Z,1605213767.701 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:42:47.702Z,1605213767.702 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:42:48.099Z,1605213768.099 [Default:CheckIn:D] Stopped
2020-11-12T20:42:48.099Z,1605213768.099 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:42:48.506Z,1605213768.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.024495 min 
2020-11-12T20:42:48.506Z,1605213768.506 [Default:CheckIn:E] Stopped
2020-11-12T20:42:48.506Z,1605213768.506 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:42:48.506Z,1605213768.506 [Default:CheckIn] Stopped
2020-11-12T20:42:48.507Z,1605213768.507 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:42:48.507Z,1605213768.507 [Default:CheckIn](INFO): Running loop #21
2020-11-12T20:42:48.507Z,1605213768.507 [Default:CheckIn] Running Loop=21
2020-11-12T20:42:48.507Z,1605213768.507 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:42:48.507Z,1605213768.507 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:42:50.517Z,1605213770.517 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204249.00,A,3648.16746,N,12147.28049,W,0.117,0.00,121120,,,A*7E
2020-11-12T20:42:50.519Z,1605213770.519 [NAL9602](INFO): GPS fix at 20201112T204249: (36.802791, -121.788008)
2020-11-12T20:42:50.565Z,1605213770.565 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:42:50.565Z,1605213770.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:42:57.714Z,1605213777.714 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0060.lzma
2020-11-12T20:42:58.717Z,1605213778.717 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0060.lzma.bak
2020-11-12T20:42:58.717Z,1605213778.717 [DataOverHttps](INFO): SBD MOMSN=13196216
2020-11-12T20:43:15.079Z,1605213795.079 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20201112T185118/Express0061.lzma
2020-11-12T20:43:16.081Z,1605213796.081 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0061.lzma.bak
2020-11-12T20:43:16.081Z,1605213796.081 [DataOverHttps](INFO): SBD MOMSN=13196219
2020-11-12T20:43:17.221Z,1605213797.221 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:43:17.221Z,1605213797.221 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:43:17.221Z,1605213797.221 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:47:52.420Z,1605214072.420 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:48:17.882Z,1605214097.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:48:17.882Z,1605214097.882 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:48:17.882Z,1605214097.882 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:48:17.882Z,1605214097.882 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:48:18.292Z,1605214098.292 [Default:CheckIn:D] Stopped
2020-11-12T20:48:18.292Z,1605214098.292 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:48:18.722Z,1605214098.722 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 121.527710 min 
2020-11-12T20:48:18.722Z,1605214098.722 [Default:CheckIn:E] Stopped
2020-11-12T20:48:18.722Z,1605214098.722 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:48:18.722Z,1605214098.722 [Default:CheckIn] Stopped
2020-11-12T20:48:18.722Z,1605214098.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:48:18.723Z,1605214098.723 [Default:CheckIn](INFO): Running loop #22
2020-11-12T20:48:18.723Z,1605214098.723 [Default:CheckIn] Running Loop=22
2020-11-12T20:48:18.731Z,1605214098.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:48:18.731Z,1605214098.731 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:48:20.694Z,1605214100.694 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204819.00,A,3648.17026,N,12147.27966,W,0.117,0.00,121120,,,A*7A
2020-11-12T20:48:20.714Z,1605214100.714 [NAL9602](INFO): GPS fix at 20201112T204819: (36.802838, -121.787994)
2020-11-12T20:48:20.749Z,1605214100.749 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:48:20.749Z,1605214100.749 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:48:31.754Z,1605214111.754 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0063.lzma
2020-11-12T20:48:32.757Z,1605214112.757 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0063.lzma.bak
2020-11-12T20:48:32.757Z,1605214112.757 [DataOverHttps](INFO): SBD MOMSN=13196275
2020-11-12T20:48:52.600Z,1605214132.600 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T185118/Express0064.lzma
2020-11-12T20:48:53.258Z,1605214133.258 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:48:53.601Z,1605214133.601 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0064.lzma.bak
2020-11-12T20:48:53.601Z,1605214133.601 [DataOverHttps](INFO): SBD MOMSN=13196278
2020-11-12T20:48:54.888Z,1605214134.888 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:48:54.888Z,1605214134.888 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:48:54.888Z,1605214134.888 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:53:55.518Z,1605214435.518 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:53:55.518Z,1605214435.518 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:53:55.518Z,1605214435.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:53:55.519Z,1605214435.519 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:53:55.926Z,1605214435.926 [Default:CheckIn:D] Stopped
2020-11-12T20:53:55.926Z,1605214435.926 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.154956 min 
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn:E] Stopped
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn] Stopped
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:53:56.340Z,1605214436.340 [Default:CheckIn](INFO): Running loop #23
2020-11-12T20:53:56.341Z,1605214436.341 [Default:CheckIn] Running Loop=23
2020-11-12T20:53:56.341Z,1605214436.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:53:56.341Z,1605214436.341 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:53:58.338Z,1605214438.338 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205357.00,A,3648.16706,N,12147.27818,W,0.058,0.00,121120,,,A*7C
2020-11-12T20:53:58.340Z,1605214438.340 [NAL9602](INFO): GPS fix at 20201112T205357: (36.802784, -121.787970)
2020-11-12T20:53:58.351Z,1605214438.351 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:53:58.371Z,1605214438.371 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:54:05.558Z,1605214445.558 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0066.lzma
2020-11-12T20:54:06.561Z,1605214446.561 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0066.lzma.bak
2020-11-12T20:54:06.561Z,1605214446.561 [DataOverHttps](INFO): SBD MOMSN=13196345
2020-11-12T20:54:29.100Z,1605214469.100 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T20:54:29.179Z,1605214469.179 [NAL9602](FAULT): received: 
+CSQ:1

OK380, 0, 0, 0, 0

OK
2020-11-12T20:54:29.179Z,1605214469.179 [NAL9602] Data Fault, FailCount= 1
2020-11-12T20:54:29.179Z,1605214469.179 [NAL9602](ERROR): Data Fault
2020-11-12T20:54:29.200Z,1605214469.200 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T20:54:29.504Z,1605214469.504 [NAL9602](INFO): Powering down
2020-11-12T20:54:30.495Z,1605214470.495 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T20:54:30.495Z,1605214470.495 [NAL9602] No Fault, FailCount= 1
2020-11-12T20:54:38.871Z,1605214478.871 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20201112T185118/Express0067.lzma
2020-11-12T20:54:39.873Z,1605214479.873 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0067.lzma.bak
2020-11-12T20:54:39.873Z,1605214479.873 [DataOverHttps](INFO): SBD MOMSN=13196356
2020-11-12T20:54:41.359Z,1605214481.359 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T20:54:41.359Z,1605214481.359 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T20:54:41.359Z,1605214481.359 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T20:54:59.522Z,1605214499.522 [NAL9602](INFO): Powering up NAL9602
2020-11-12T20:55:10.412Z,1605214510.412 [NAL9602](INFO): NAL9602 initialized
2020-11-12T20:55:41.516Z,1605214541.516 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T20:56:04.570Z,1605214564.570 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-11-12T20:59:42.050Z,1605214782.050 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T20:59:42.050Z,1605214782.050 [Default:CheckIn:C.Wait] Stopped
2020-11-12T20:59:42.050Z,1605214782.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T20:59:42.050Z,1605214782.050 [Default:CheckIn:D] Running Loop=1
2020-11-12T20:59:42.482Z,1605214782.482 [Default:CheckIn:D] Stopped
2020-11-12T20:59:42.482Z,1605214782.482 [Default:CheckIn:E] Running Loop=1
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.930868 min 
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn:E] Stopped
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn] Stopped
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn](INFO): Running loop #24
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn] Running Loop=24
2020-11-12T20:59:42.876Z,1605214782.876 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T20:59:42.877Z,1605214782.877 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T20:59:44.874Z,1605214784.874 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205943.00,A,3648.17067,N,12147.28268,W,0.156,79.74,121120,,,A*42
2020-11-12T20:59:44.876Z,1605214784.876 [NAL9602](INFO): GPS fix at 20201112T205943: (36.802844, -121.788045)
2020-11-12T20:59:44.930Z,1605214784.930 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T20:59:44.930Z,1605214784.930 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T20:59:47.288Z,1605214787.288 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T20:59:52.086Z,1605214792.086 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0069.lzma
2020-11-12T20:59:53.089Z,1605214793.089 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0069.lzma.bak
2020-11-12T20:59:53.089Z,1605214793.089 [DataOverHttps](INFO): SBD MOMSN=13196401
2020-11-12T21:00:09.319Z,1605214809.319 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20201112T185118/Express0070.lzma
2020-11-12T21:00:10.321Z,1605214810.321 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0070.lzma.bak
2020-11-12T21:00:10.321Z,1605214810.321 [DataOverHttps](INFO): SBD MOMSN=13196404
2020-11-12T21:00:11.546Z,1605214811.546 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:00:11.546Z,1605214811.546 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:00:11.546Z,1605214811.546 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:00:17.992Z,1605214817.992 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:05:12.199Z,1605215112.199 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:05:12.199Z,1605215112.199 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:05:12.199Z,1605215112.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:05:12.200Z,1605215112.200 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:05:12.624Z,1605215112.624 [Default:CheckIn:D] Stopped
2020-11-12T21:05:12.624Z,1605215112.624 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:05:13.007Z,1605215113.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.433236 min 
2020-11-12T21:05:13.007Z,1605215113.007 [Default:CheckIn:E] Stopped
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn] Stopped
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn](INFO): Running loop #25
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn] Running Loop=25
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:05:13.008Z,1605215113.008 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:05:15.018Z,1605215115.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210514.00,A,3648.16901,N,12147.28391,W,0.330,79.74,121120,,,A*45
2020-11-12T21:05:15.021Z,1605215115.021 [NAL9602](INFO): GPS fix at 20201112T210514: (36.802817, -121.788065)
2020-11-12T21:05:15.032Z,1605215115.032 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:05:15.032Z,1605215115.032 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:05:31.322Z,1605215131.322 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T185118/Courier0072.lzma
2020-11-12T21:05:32.325Z,1605215132.325 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0072.lzma.bak
2020-11-12T21:05:32.325Z,1605215132.325 [DataOverHttps](INFO): SBD MOMSN=13196470
2020-11-12T21:05:57.671Z,1605215157.671 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20201112T185118/Express0073.lzma
2020-11-12T21:05:58.673Z,1605215158.673 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0073.lzma.bak
2020-11-12T21:05:58.673Z,1605215158.673 [DataOverHttps](INFO): SBD MOMSN=13196504
2020-11-12T21:05:59.875Z,1605215159.875 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:05:59.875Z,1605215159.875 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:05:59.876Z,1605215159.876 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:06:03.094Z,1605215163.094 [NAL9602](INFO): SBD MO Status=0, MOMSN=11381, MT Status=0, MTMSN=0
2020-11-12T21:06:03.094Z,1605215163.094 [NAL9602](INFO): No messages in MT queue
2020-11-12T21:06:33.813Z,1605215193.813 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:09:19.917Z,1605215359.917 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-12T21:11:00.514Z,1605215460.514 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:11:00.514Z,1605215460.514 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:11:00.514Z,1605215460.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:11:00.514Z,1605215460.514 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:11:00.919Z,1605215460.919 [Default:CheckIn:D] Stopped
2020-11-12T21:11:00.919Z,1605215460.919 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.238167 min 
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn:E] Stopped
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn] Stopped
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:11:01.336Z,1605215461.336 [Default:CheckIn](INFO): Running loop #26
2020-11-12T21:11:01.337Z,1605215461.337 [Default:CheckIn] Running Loop=26
2020-11-12T21:11:01.337Z,1605215461.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:11:01.337Z,1605215461.337 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:11:03.334Z,1605215463.334 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211102.00,A,3648.16700,N,12147.27998,W,0.369,79.74,121120,,,A*48
2020-11-12T21:11:03.336Z,1605215463.336 [NAL9602](INFO): GPS fix at 20201112T211102: (36.802783, -121.788000)
2020-11-12T21:11:03.347Z,1605215463.347 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:11:03.347Z,1605215463.347 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:11:11.094Z,1605215471.094 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0075.lzma
2020-11-12T21:11:12.097Z,1605215472.097 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0075.lzma.bak
2020-11-12T21:11:12.097Z,1605215472.097 [DataOverHttps](INFO): SBD MOMSN=13196515
2020-11-12T21:11:28.327Z,1605215488.327 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20201112T185118/Express0076.lzma
2020-11-12T21:11:29.329Z,1605215489.329 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0076.lzma.bak
2020-11-12T21:11:29.329Z,1605215489.329 [DataOverHttps](INFO): SBD MOMSN=13196518
2020-11-12T21:11:30.840Z,1605215490.840 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:11:30.840Z,1605215490.840 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:11:30.840Z,1605215490.840 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:13:30.393Z,1605215610.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=11382, MT Status=2, MTMSN=0
2020-11-12T21:13:30.394Z,1605215610.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T21:15:10.629Z,1605215710.629 [NAL9602](INFO): SBD MO Status=2, MOMSN=11382, MT Status=2, MTMSN=0
2020-11-12T21:15:10.629Z,1605215710.629 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T21:15:21.966Z,1605215721.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=11382, MT Status=0, MTMSN=0
2020-11-12T21:15:21.966Z,1605215721.966 [NAL9602](INFO): No messages in MT queue
2020-11-12T21:15:52.656Z,1605215752.656 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:16:31.563Z,1605215791.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:16:31.563Z,1605215791.563 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:16:31.563Z,1605215791.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:16:31.563Z,1605215791.563 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:16:31.930Z,1605215791.930 [Default:CheckIn:D] Stopped
2020-11-12T21:16:31.930Z,1605215791.930 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:16:32.335Z,1605215792.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.755013 min 
2020-11-12T21:16:32.335Z,1605215792.335 [Default:CheckIn:E] Stopped
2020-11-12T21:16:32.335Z,1605215792.335 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:16:32.335Z,1605215792.335 [Default:CheckIn] Stopped
2020-11-12T21:16:32.335Z,1605215792.335 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:16:32.336Z,1605215792.336 [Default:CheckIn](INFO): Running loop #27
2020-11-12T21:16:32.336Z,1605215792.336 [Default:CheckIn] Running Loop=27
2020-11-12T21:16:32.336Z,1605215792.336 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:16:32.336Z,1605215792.336 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:16:34.330Z,1605215794.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211633.00,A,3648.16373,N,12147.28068,W,0.680,0.00,121120,,,A*7B
2020-11-12T21:16:34.332Z,1605215794.332 [NAL9602](INFO): GPS fix at 20201112T211633: (36.802729, -121.788011)
2020-11-12T21:16:34.408Z,1605215794.408 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:16:34.408Z,1605215794.408 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:16:43.182Z,1605215803.182 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0078.lzma
2020-11-12T21:16:44.185Z,1605215804.185 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0078.lzma.bak
2020-11-12T21:16:44.185Z,1605215804.185 [DataOverHttps](INFO): SBD MOMSN=13196621
2020-11-12T21:17:00.371Z,1605215820.371 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20201112T185118/Express0079.lzma
2020-11-12T21:17:01.373Z,1605215821.373 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0079.lzma.bak
2020-11-12T21:17:01.373Z,1605215821.373 [DataOverHttps](INFO): SBD MOMSN=13196624
2020-11-12T21:17:02.569Z,1605215822.569 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:17:02.569Z,1605215822.569 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:17:02.570Z,1605215822.570 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:17:22.742Z,1605215842.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=11383, MT Status=2, MTMSN=0
2020-11-12T21:17:22.742Z,1605215842.742 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-11-12T21:21:36.092Z,1605216096.092 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:22:03.186Z,1605216123.186 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:22:03.186Z,1605216123.186 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:22:03.186Z,1605216123.186 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:22:03.187Z,1605216123.187 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:22:03.609Z,1605216123.609 [Default:CheckIn:D] Stopped
2020-11-12T21:22:03.609Z,1605216123.609 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:22:03.982Z,1605216123.982 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.283008 min 
2020-11-12T21:22:03.982Z,1605216123.982 [Default:CheckIn:E] Stopped
2020-11-12T21:22:03.982Z,1605216123.982 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:22:03.982Z,1605216123.982 [Default:CheckIn] Stopped
2020-11-12T21:22:03.983Z,1605216123.983 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:22:03.983Z,1605216123.983 [Default:CheckIn](INFO): Running loop #28
2020-11-12T21:22:03.983Z,1605216123.983 [Default:CheckIn] Running Loop=28
2020-11-12T21:22:03.983Z,1605216123.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:22:03.983Z,1605216123.983 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:22:05.989Z,1605216125.989 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212205.00,A,3648.16972,N,12147.27920,W,0.330,0.00,121120,,,A*76
2020-11-12T21:22:05.991Z,1605216125.991 [NAL9602](INFO): GPS fix at 20201112T212205: (36.802829, -121.787987)
2020-11-12T21:22:06.013Z,1605216126.013 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:22:06.013Z,1605216126.013 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:22:14.038Z,1605216134.038 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201112T185118/Courier0081.lzma
2020-11-12T21:22:15.041Z,1605216135.041 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0081.lzma.bak
2020-11-12T21:22:15.041Z,1605216135.041 [DataOverHttps](INFO): SBD MOMSN=13196636
2020-11-12T21:22:37.587Z,1605216157.587 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201112T185118/Express0082.lzma
2020-11-12T21:22:38.422Z,1605216158.422 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:22:38.589Z,1605216158.589 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0082.lzma.bak
2020-11-12T21:22:38.589Z,1605216158.589 [DataOverHttps](INFO): SBD MOMSN=13196639
2020-11-12T21:22:40.055Z,1605216160.055 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:22:40.055Z,1605216160.055 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:22:40.055Z,1605216160.055 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:27:40.761Z,1605216460.761 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:27:40.762Z,1605216460.762 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:27:40.762Z,1605216460.762 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:27:40.762Z,1605216460.762 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:27:41.183Z,1605216461.183 [Default:CheckIn:D] Stopped
2020-11-12T21:27:41.183Z,1605216461.183 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:27:41.635Z,1605216461.635 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 160.909229 min 
2020-11-12T21:27:41.635Z,1605216461.635 [Default:CheckIn:E] Stopped
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn] Stopped
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn](INFO): Running loop #29
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn] Running Loop=29
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:27:41.636Z,1605216461.636 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:27:43.570Z,1605216463.570 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212742.00,A,3648.16892,N,12147.28511,W,0.039,0.00,121120,,,A*74
2020-11-12T21:27:43.572Z,1605216463.572 [NAL9602](INFO): GPS fix at 20201112T212742: (36.802815, -121.788085)
2020-11-12T21:27:43.584Z,1605216463.584 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:27:43.584Z,1605216463.584 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:27:51.147Z,1605216471.147 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201112T185118/Courier0084.lzma
2020-11-12T21:27:52.149Z,1605216472.149 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0084.lzma.bak
2020-11-12T21:27:52.149Z,1605216472.149 [DataOverHttps](INFO): SBD MOMSN=13196745
2020-11-12T21:28:08.587Z,1605216488.587 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20201112T185118/Express0085.lzma
2020-11-12T21:28:09.738Z,1605216489.738 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0085.lzma.bak
2020-11-12T21:28:09.739Z,1605216489.739 [DataOverHttps](INFO): SBD MOMSN=13196748
2020-11-12T21:28:11.102Z,1605216491.102 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:28:11.102Z,1605216491.102 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:28:11.103Z,1605216491.103 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:28:14.272Z,1605216494.272 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-11-12T21:28:14.355Z,1605216494.355 [NAL9602](FAULT): received: 
+CSQ:0

OK383, 2, 0, 0, 0

OK
2020-11-12T21:28:14.355Z,1605216494.355 [NAL9602] Data Fault, FailCount= 1
2020-11-12T21:28:14.355Z,1605216494.355 [NAL9602](ERROR): Data Fault
2020-11-12T21:28:14.577Z,1605216494.577 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:28:14.672Z,1605216494.672 [NAL9602](INFO): Powering down
2020-11-12T21:28:15.617Z,1605216495.617 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:28:15.617Z,1605216495.617 [NAL9602] No Fault, FailCount= 1
2020-11-12T21:28:44.975Z,1605216524.975 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:28:55.884Z,1605216535.884 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:29:26.996Z,1605216566.996 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:32:31.436Z,1605216751.436 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file).
2020-11-12T21:32:58.804Z,1605216778.804 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-11-12T21:32:58.804Z,1605216778.804 [DropWeight] Hardware Fault, FailCount= 1
2020-11-12T21:32:58.804Z,1605216778.804 [DropWeight](ERROR): Hardware Fault
2020-11-12T21:32:58.856Z,1605216778.856 [CommandLine](FAULT): Scheduling is paused
2020-11-12T21:32:58.856Z,1605216778.856 [CBIT](INFO): Critical error at 20201112T213258
2020-11-12T21:32:58.858Z,1605216778.858 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-11-12T21:32:58.859Z,1605216778.859 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-11-12T21:32:59.313Z,1605216779.313 [CBIT](INFO): Critical error at 20201112T213258
2020-11-12T21:33:11.838Z,1605216791.838 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:33:11.839Z,1605216791.839 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:33:11.839Z,1605216791.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:33:11.839Z,1605216791.839 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:33:12.205Z,1605216792.205 [Default:CheckIn:D] Stopped
2020-11-12T21:33:12.205Z,1605216792.205 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.426270 min 
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn:E] Stopped
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn] Stopped
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn](INFO): Running loop #30
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn] Running Loop=30
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:33:12.614Z,1605216792.614 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:33:14.574Z,1605216794.574 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213312.00,A,3648.16969,N,12147.28184,W,0.194,0.00,121120,,,A*7F
2020-11-12T21:33:14.577Z,1605216794.577 [NAL9602](INFO): GPS fix at 20201112T213312: (36.802828, -121.788031)
2020-11-12T21:33:14.598Z,1605216794.598 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:33:14.598Z,1605216794.598 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:33:16.176Z,1605216796.176 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:33:22.579Z,1605216802.579 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20201112T185118/Courier0087.lzma
2020-11-12T21:33:23.581Z,1605216803.581 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0087.lzma.bak
2020-11-12T21:33:23.582Z,1605216803.582 [DataOverHttps](INFO): SBD MOMSN=13196834
2020-11-12T21:33:40.095Z,1605216820.095 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20201112T185118/Express0088.lzma
2020-11-12T21:33:41.097Z,1605216821.097 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0088.lzma.bak
2020-11-12T21:33:41.097Z,1605216821.097 [DataOverHttps](INFO): SBD MOMSN=13196839
2020-11-12T21:33:42.458Z,1605216822.458 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:33:42.458Z,1605216822.458 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:33:42.458Z,1605216822.458 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:33:46.884Z,1605216826.884 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:34:05.894Z,1605216845.894 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2020-11-12T21:34:05.897Z,1605216845.897 [BPC1](INFO): Received data from all battery sticks.
2020-11-12T21:38:43.199Z,1605217123.199 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:38:43.199Z,1605217123.199 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:38:43.199Z,1605217123.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:38:43.199Z,1605217123.199 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:38:43.598Z,1605217123.598 [Default:CheckIn:D] Stopped
2020-11-12T21:38:43.598Z,1605217123.598 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:38:44.047Z,1605217124.047 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.949479 min 
2020-11-12T21:38:44.047Z,1605217124.047 [Default:CheckIn:E] Stopped
2020-11-12T21:38:44.047Z,1605217124.047 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:38:44.047Z,1605217124.047 [Default:CheckIn] Stopped
2020-11-12T21:38:44.047Z,1605217124.047 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:38:44.048Z,1605217124.048 [Default:CheckIn](INFO): Running loop #31
2020-11-12T21:38:44.048Z,1605217124.048 [Default:CheckIn] Running Loop=31
2020-11-12T21:38:44.048Z,1605217124.048 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:38:44.048Z,1605217124.048 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:43:44.234Z,1605217424.234 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-12T21:38:44.0Z
2020-11-12T21:43:44.234Z,1605217424.234 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:43:44.234Z,1605217424.234 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:43:48.264Z,1605217428.264 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:43:51.990Z,1605217431.990 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20201112T185118/Courier0090.lzma
2020-11-12T21:43:52.993Z,1605217432.993 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0090.lzma.bak
2020-11-12T21:43:52.993Z,1605217432.993 [DataOverHttps](INFO): SBD MOMSN=13196944
2020-11-12T21:44:09.219Z,1605217449.219 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20201112T185118/Express0091.lzma
2020-11-12T21:44:10.221Z,1605217450.221 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0091.lzma.bak
2020-11-12T21:44:10.221Z,1605217450.221 [DataOverHttps](INFO): SBD MOMSN=13196946
2020-11-12T21:44:11.719Z,1605217451.719 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:44:11.719Z,1605217451.719 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:44:11.719Z,1605217451.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:44:18.976Z,1605217458.976 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:47:59.163Z,1605217679.163 [CBIT](INFO): Clearing failed state for component DropWeight
2020-11-12T21:47:59.163Z,1605217679.163 [DropWeight] No Fault, FailCount= 1
2020-11-12T21:49:12.294Z,1605217752.294 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-11-12T21:49:12.294Z,1605217752.294 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:49:12.294Z,1605217752.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:49:12.294Z,1605217752.294 [Default:CheckIn:D] Running Loop=1
2020-11-12T21:49:12.707Z,1605217752.707 [Default:CheckIn:D] Stopped
2020-11-12T21:49:12.707Z,1605217752.707 [Default:CheckIn:E] Running Loop=1
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 182.434635 min 
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn:E] Stopped
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn] Stopped
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn](INFO): Running loop #32
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn] Running Loop=32
2020-11-12T21:49:13.105Z,1605217753.105 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-11-12T21:49:13.106Z,1605217753.106 [Default:CheckIn:Read_GPS] Running Loop=1
2020-11-12T21:49:14.700Z,1605217754.700 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-11-12T21:49:14.700Z,1605217754.700 [NAL9602] Data Fault, FailCount= 1
2020-11-12T21:49:14.700Z,1605217754.700 [NAL9602](ERROR): Data Fault
2020-11-12T21:49:14.746Z,1605217754.746 [CBIT](ERROR): Data Fault in component: NAL9602
2020-11-12T21:49:15.108Z,1605217755.108 [NAL9602](INFO): Powering down
2020-11-12T21:49:15.938Z,1605217755.938 [CBIT](INFO): Clearing failed state for component NAL9602
2020-11-12T21:49:15.938Z,1605217755.938 [NAL9602] No Fault, FailCount= 1
2020-11-12T21:49:45.402Z,1605217785.402 [NAL9602](INFO): Powering up NAL9602
2020-11-12T21:49:56.316Z,1605217796.316 [NAL9602](INFO): NAL9602 initialized
2020-11-12T21:52:06.052Z,1605217926.052 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-11-12T21:54:13.277Z,1605218053.277 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-11-12T21:49:13.1Z
2020-11-12T21:54:13.278Z,1605218053.278 [Default:CheckIn:Read_GPS] Stopped
2020-11-12T21:54:13.278Z,1605218053.278 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-11-12T21:54:19.418Z,1605218059.418 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201112T185118/Courier0093.lzma
2020-11-12T21:54:20.421Z,1605218060.421 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Courier0093.lzma.bak
2020-11-12T21:54:20.421Z,1605218060.421 [DataOverHttps](INFO): SBD MOMSN=13197043
2020-11-12T21:54:37.031Z,1605218077.031 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20201112T185118/Express0094.lzma
2020-11-12T21:54:38.033Z,1605218078.033 [DataOverHttps](INFO): Moved sent file to Logs/20201112T185118/Express0094.lzma.bak
2020-11-12T21:54:38.033Z,1605218078.033 [DataOverHttps](INFO): SBD MOMSN=13197045
2020-11-12T21:54:39.181Z,1605218079.181 [Default:CheckIn:Read_Iridium] Stopped
2020-11-12T21:54:39.181Z,1605218079.181 [Default:CheckIn:C.Wait] Running Loop=1
2020-11-12T21:54:39.181Z,1605218079.181 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-11-12T21:54:59.354Z,1605218099.354 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-11-12T21:55:30.064Z,1605218130.064 [NAL9602](INFO): Not Powering down - fast GPS
2020-11-12T21:55:37.745Z,1605218137.745 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-11-12T21:56:15.869Z,1605218175.869 [CommandLine](IMPORTANT): got command quit
2020-11-12T21:56:16.875Z,1605218176.875 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:16.875Z,1605218176.875 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:16.959Z,1605218176.959 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-11-12T21:56:16.960Z,1605218176.960 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:16.960Z,1605218176.960 [CommandLine](INFO): Join timeout helper Thread ID is 4059
2020-11-12T21:56:16.967Z,1605218176.967 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-11-12T21:56:16.967Z,1605218176.967 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:16.968Z,1605218176.968 [NavChartDb](INFO): Join timeout helper Thread ID is 4060
2020-11-12T21:56:17.171Z,1605218177.171 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:17.171Z,1605218177.171 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.175Z,1605218177.175 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-11-12T21:56:17.175Z,1605218177.175 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.175Z,1605218177.175 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 4061
2020-11-12T21:56:17.496Z,1605218177.496 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:17.496Z,1605218177.496 [WetLabsUBAT](INFO): Powering down
2020-11-12T21:56:17.497Z,1605218177.497 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.511Z,1605218177.511 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-11-12T21:56:17.511Z,1605218177.511 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.512Z,1605218177.512 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4062
2020-11-12T21:56:17.891Z,1605218177.891 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:17.892Z,1605218177.892 [WetLabsBB2FL](INFO): Powering down
2020-11-12T21:56:17.892Z,1605218177.892 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.895Z,1605218177.895 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-11-12T21:56:17.895Z,1605218177.895 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:17.896Z,1605218177.896 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4063
2020-11-12T21:56:18.275Z,1605218178.275 [CTD_Seabird](INFO): Powering down
2020-11-12T21:56:18.287Z,1605218178.287 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:18.287Z,1605218178.287 [CTD_Seabird](INFO): Powering down
2020-11-12T21:56:18.299Z,1605218178.299 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.300Z,1605218178.300 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler
2020-11-12T21:56:18.301Z,1605218178.301 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.301Z,1605218178.301 [BackSeatDriver](INFO): Join timeout helper Thread ID is 4064
2020-11-12T21:56:18.356Z,1605218178.356 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:18.356Z,1605218178.356 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.364Z,1605218178.364 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-11-12T21:56:18.364Z,1605218178.364 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.364Z,1605218178.364 [Radio_Surface](INFO): Join timeout helper Thread ID is 4065
2020-11-12T21:56:18.759Z,1605218178.759 [Radio_Surface](INFO): Powering down
2020-11-12T21:56:18.760Z,1605218178.760 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:18.760Z,1605218178.760 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.772Z,1605218178.772 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-11-12T21:56:18.772Z,1605218178.772 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:18.772Z,1605218178.772 [Onboard](INFO): Join timeout helper Thread ID is 4066
2020-11-12T21:56:19.639Z,1605218179.639 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-11-12T21:56:22.492Z,1605218182.492 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:22.493Z,1605218182.493 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.496Z,1605218182.496 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-11-12T21:56:22.496Z,1605218182.496 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.496Z,1605218182.496 [DataOverHttps](INFO): Join timeout helper Thread ID is 4067
2020-11-12T21:56:22.651Z,1605218182.651 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:22.651Z,1605218182.651 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.660Z,1605218182.660 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-11-12T21:56:22.660Z,1605218182.660 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.660Z,1605218182.660 [logger](INFO): Join timeout helper Thread ID is 4068
2020-11-12T21:56:22.679Z,1605218182.679 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:22.679Z,1605218182.679 [logger ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.696Z,1605218182.696 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-11-12T21:56:22.696Z,1605218182.696 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.696Z,1605218182.696 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-11-12T21:56:22.696Z,1605218182.696 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:22.696Z,1605218182.696 [controlThread](INFO): Join timeout helper Thread ID is 4069
2020-11-12T21:56:22.968Z,1605218182.968 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-11-12T21:56:22.969Z,1605218182.969 [controlThread](DEBUG): Uninitializing ControlThread
2020-11-12T21:56:22.971Z,1605218182.971 [AHRS_M2](INFO): Powering down
2020-11-12T21:56:23.044Z,1605218183.044 [NAL9602](INFO): Powering down
2020-11-12T21:56:23.115Z,1605218183.115 [RDI_Pathfinder](INFO): Powering down
2020-11-12T21:56:23.116Z,1605218183.116 [DAT](INFO): Powering down
2020-11-12T21:56:23.236Z,1605218183.236 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-11-12T21:56:23.237Z,1605218183.237 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-11-12T21:56:23.237Z,1605218183.237 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-11-12T21:56:23.238Z,1605218183.238 [MissionManager](INFO): Uninitializing Mission Default
2020-11-12T21:56:23.238Z,1605218183.238 [Default] Stopped
2020-11-12T21:56:23.238Z,1605218183.238 [Default](DEBUG): Aggregate::uninitialize Default
2020-11-12T21:56:23.238Z,1605218183.238 [Default:B.GoToSurface] Stopped
2020-11-12T21:56:23.238Z,1605218183.238 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-11-12T21:56:23.238Z,1605218183.238 [Default:CheckIn] Stopped
2020-11-12T21:56:23.238Z,1605218183.238 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-11-12T21:56:23.238Z,1605218183.238 [Default:CheckIn:C.Wait] Stopped
2020-11-12T21:56:23.238Z,1605218183.238 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-11-12T21:56:23.241Z,1605218183.241 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-11-12T21:56:23.241Z,1605218183.241 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-11-12T21:56:23.241Z,1605218183.241 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-11-12T21:56:23.241Z,1605218183.241 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-11-12T21:56:23.242Z,1605218183.242 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-11-12T21:56:23.242Z,1605218183.242 [BuoyancyServo](INFO): Powering down
2020-11-12T21:56:23.255Z,1605218183.255 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-11-12T21:56:23.255Z,1605218183.255 [ElevatorServo](INFO): Powering down
2020-11-12T21:56:23.256Z,1605218183.256 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-11-12T21:56:23.256Z,1605218183.256 [MassServo](INFO): Powering down
2020-11-12T21:56:23.257Z,1605218183.257 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-11-12T21:56:23.257Z,1605218183.257 [RudderServo](INFO): Powering down
2020-11-12T21:56:23.258Z,1605218183.258 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-11-12T21:56:23.258Z,1605218183.258 [ThrusterServo](INFO): Powering down
2020-11-12T21:56:23.258Z,1605218183.258 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-11-12T21:56:23.259Z,1605218183.259 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-11-12T21:56:23.259Z,1605218183.259 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-11-12T21:56:23.259Z,1605218183.259 [CBIT](DEBUG): Powering off loads.
2020-11-12T21:56:23.271Z,1605218183.271 [CBIT](DEBUG): Disabling WDT.
2020-11-12T21:56:23.283Z,1605218183.283 [CBIT](DEBUG): Opening all GF detection circuits.
2020-11-12T21:56:23.284Z,1605218183.284 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.381Z,1605218183.381 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.383Z,1605218183.383 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.388Z,1605218183.388 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.448Z,1605218183.448 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.449Z,1605218183.449 [WetLabsUBAT](INFO): WetLabs destructor: dataLog is open - close it
2020-11-12T21:56:23.449Z,1605218183.449 [WetLabsUBAT](INFO): WetLabs destructor: done
2020-11-12T21:56:23.501Z,1605218183.501 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.504Z,1605218183.504 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.507Z,1605218183.507 [BackSeatDriver ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.517Z,1605218183.517 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-11-12T21:56:23.613Z,1605218183.613 [logger ThreadHandler](INFO): Thread cancelled.