2020-12-11T23:38:33.081Z,1607729913.081 [CommandLine](IMPORTANT): got command restart logs
2020-12-11T23:40:05.070Z,1607730005.070 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-11T23:40:07.079Z,1607730007.079 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239277
2020-12-11T23:41:04.169Z,1607730064.169 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004613
2020-12-11T23:41:15.681Z,1607730075.681 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2020-12-11T23:41:22.031Z,1607730082.031 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-11T23:41:22.033Z,1607730082.033 [BPC1](INFO): Received data from all battery sticks.
2020-12-11T23:42:00.849Z,1607730120.849 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-11T23:42:00.849Z,1607730120.849 [Default:CheckIn:C.Wait] Stopped
2020-12-11T23:42:00.849Z,1607730120.849 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-11T23:42:00.849Z,1607730120.849 [Default:CheckIn:D] Running Loop=1
2020-12-11T23:42:01.268Z,1607730121.268 [Default:CheckIn:D] Stopped
2020-12-11T23:42:01.268Z,1607730121.268 [Default:CheckIn:E] Running Loop=1
2020-12-11T23:42:01.664Z,1607730121.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.751223 min 
2020-12-11T23:42:01.664Z,1607730121.664 [Default:CheckIn:E] Stopped
2020-12-11T23:42:01.664Z,1607730121.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-11T23:42:01.664Z,1607730121.664 [Default:CheckIn] Stopped
2020-12-11T23:42:01.664Z,1607730121.664 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-11T23:42:01.665Z,1607730121.665 [Default:CheckIn](INFO): Running loop #2
2020-12-11T23:42:01.665Z,1607730121.665 [Default:CheckIn] Running Loop=2
2020-12-11T23:42:01.665Z,1607730121.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-11T23:42:01.665Z,1607730121.665 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-11T23:42:03.649Z,1607730123.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234205.00,A,3648.43139,N,12147.81539,W,24.784,119.01,111220,,,D*4D
2020-12-11T23:42:03.651Z,1607730123.651 [NAL9602](INFO): GPS fix at 20201211T234205: (36.807190, -121.796923)
2020-12-11T23:42:03.663Z,1607730123.663 [Default:CheckIn:Read_GPS] Stopped
2020-12-11T23:42:03.663Z,1607730123.663 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-11T23:42:12.198Z,1607730132.198 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201211T211803/Courier0055.lzma
2020-12-11T23:42:13.200Z,1607730133.200 [DataOverHttps](INFO): Moved sent file to Logs/20201211T211803/Courier0055.lzma.bak
2020-12-11T23:42:13.200Z,1607730133.200 [DataOverHttps](INFO): SBD MOMSN=13491497
2020-12-11T23:42:36.878Z,1607730156.878 [DataOverHttps](INFO): Sending 309 bytes from file Logs/20201211T233833/Courier0000.lzma
2020-12-11T23:42:37.882Z,1607730157.882 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0000.lzma.bak
2020-12-11T23:42:37.882Z,1607730157.882 [DataOverHttps](INFO): SBD MOMSN=13491500
2020-12-11T23:42:56.650Z,1607730176.650 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20201211T211803/Express0056.lzma
2020-12-11T23:42:57.652Z,1607730177.652 [DataOverHttps](INFO): Moved sent file to Logs/20201211T211803/Express0056.lzma.bak
2020-12-11T23:42:57.652Z,1607730177.652 [DataOverHttps](INFO): SBD MOMSN=13491504
2020-12-11T23:43:14.370Z,1607730194.370 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20201211T233833/Express0001.lzma
2020-12-11T23:43:15.372Z,1607730195.372 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0001.lzma.bak
2020-12-11T23:43:15.373Z,1607730195.373 [DataOverHttps](INFO): SBD MOMSN=13491512
2020-12-11T23:43:17.321Z,1607730197.321 [Default:CheckIn:Read_Iridium] Stopped
2020-12-11T23:43:17.321Z,1607730197.321 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-11T23:43:17.321Z,1607730197.321 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-11T23:44:32.849Z,1607730272.849 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=12142, MT Status=1, MTMSN=694
2020-12-11T23:44:32.849Z,1607730272.849 [NAL9602](INFO): Data available in MT queue
2020-12-11T23:44:33.327Z,1607730273.327 [NAL9602](INFO): Received command:stop
2020-12-11T23:44:33.343Z,1607730273.343 [CommandLine](IMPORTANT): got command stop
2020-12-11T23:45:03.954Z,1607730303.954 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-11T23:48:17.977Z,1607730497.977 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-11T23:48:17.978Z,1607730497.978 [Default:CheckIn:C.Wait] Stopped
2020-12-11T23:48:17.978Z,1607730497.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-11T23:48:17.987Z,1607730497.987 [Default:CheckIn:D] Running Loop=1
2020-12-11T23:48:18.366Z,1607730498.366 [Default:CheckIn:D] Stopped
2020-12-11T23:48:18.366Z,1607730498.366 [Default:CheckIn:E] Running Loop=1
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.036183 min 
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn:E] Stopped
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn] Stopped
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn](INFO): Running loop #3
2020-12-11T23:48:18.785Z,1607730498.785 [Default:CheckIn] Running Loop=3
2020-12-11T23:48:18.786Z,1607730498.786 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-11T23:48:18.786Z,1607730498.786 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-11T23:48:20.776Z,1607730500.776 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234822.00,A,3648.27592,N,12147.14833,W,4.179,193.48,111220,,,D*77
2020-12-11T23:48:20.778Z,1607730500.778 [NAL9602](INFO): GPS fix at 20201211T234822: (36.804599, -121.785805)
2020-12-11T23:48:20.825Z,1607730500.825 [Default:CheckIn:Read_GPS] Stopped
2020-12-11T23:48:20.826Z,1607730500.826 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-11T23:48:32.198Z,1607730512.198 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201211T233833/Courier0006.lzma
2020-12-11T23:48:33.204Z,1607730513.204 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0006.lzma.bak
2020-12-11T23:48:33.204Z,1607730513.204 [DataOverHttps](INFO): SBD MOMSN=13491578
2020-12-11T23:48:51.962Z,1607730531.962 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20201211T233833/Express0007.lzma
2020-12-11T23:48:52.964Z,1607730532.964 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0007.lzma.bak
2020-12-11T23:48:52.964Z,1607730532.964 [DataOverHttps](INFO): SBD MOMSN=13491581
2020-12-11T23:48:54.828Z,1607730534.828 [Default:CheckIn:Read_Iridium] Stopped
2020-12-11T23:48:54.828Z,1607730534.828 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-11T23:48:54.829Z,1607730534.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-11T23:49:04.901Z,1607730544.901 [NAL9602](INFO): SBD MO Status=2, MOMSN=12143, MT Status=2, MTMSN=0
2020-12-11T23:49:04.901Z,1607730544.901 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-11T23:50:22.469Z,1607730622.469 [NAL9602](INFO): SBD MO Status=2, MOMSN=12143, MT Status=2, MTMSN=0
2020-12-11T23:50:22.469Z,1607730622.469 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-11T23:51:05.701Z,1607730665.701 [NAL9602](INFO): SBD MO Status=2, MOMSN=12143, MT Status=2, MTMSN=0
2020-12-11T23:51:05.701Z,1607730665.701 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-11T23:53:23.460Z,1607730803.460 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-11T23:53:54.167Z,1607730834.167 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-11T23:53:55.408Z,1607730835.408 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-11T23:53:55.408Z,1607730835.408 [Default:CheckIn:C.Wait] Stopped
2020-12-11T23:53:55.408Z,1607730835.408 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-11T23:53:55.409Z,1607730835.409 [Default:CheckIn:D] Running Loop=1
2020-12-11T23:53:55.794Z,1607730835.794 [Default:CheckIn:D] Stopped
2020-12-11T23:53:55.794Z,1607730835.794 [Default:CheckIn:E] Running Loop=1
2020-12-11T23:53:56.203Z,1607730836.203 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.659991 min 
2020-12-11T23:53:56.203Z,1607730836.203 [Default:CheckIn:E] Stopped
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn] Stopped
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn](INFO): Running loop #4
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn] Running Loop=4
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-11T23:53:56.204Z,1607730836.204 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-11T23:53:58.216Z,1607730838.216 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235400.00,A,3648.16319,N,12147.21719,W,0.078,222.59,111220,,,A*74
2020-12-11T23:53:58.218Z,1607730838.218 [NAL9602](INFO): GPS fix at 20201211T235400: (36.802720, -121.786953)
2020-12-11T23:53:58.241Z,1607730838.241 [Default:CheckIn:Read_GPS] Stopped
2020-12-11T23:53:58.241Z,1607730838.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-11T23:54:05.614Z,1607730845.614 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20201211T233833/Courier0009.lzma
2020-12-11T23:54:06.616Z,1607730846.616 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0009.lzma.bak
2020-12-11T23:54:06.616Z,1607730846.616 [DataOverHttps](INFO): SBD MOMSN=13491594
2020-12-11T23:54:26.710Z,1607730866.710 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20201211T233833/Express0010.lzma
2020-12-11T23:54:27.712Z,1607730867.712 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0010.lzma.bak
2020-12-11T23:54:27.712Z,1607730867.712 [DataOverHttps](INFO): SBD MOMSN=13491599
2020-12-11T23:54:28.988Z,1607730868.988 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-11T23:54:29.082Z,1607730869.082 [NAL9602](FAULT): received: 
+CSQ:0

OK143, 2, 0, 0, 0

OK
2020-12-11T23:54:29.082Z,1607730869.082 [NAL9602] Data Fault, FailCount= 1
2020-12-11T23:54:29.082Z,1607730869.082 [NAL9602](ERROR): Data Fault
2020-12-11T23:54:29.225Z,1607730869.225 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-11T23:54:29.395Z,1607730869.395 [NAL9602](INFO): Powering down
2020-12-11T23:54:29.808Z,1607730869.808 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-12-11T23:54:29.808Z,1607730869.808 [NAL9602] Hardware Fault, FailCount= 1
2020-12-11T23:54:29.808Z,1607730869.808 [NAL9602](ERROR): Hardware Fault
2020-12-11T23:54:29.891Z,1607730869.891 [Default:CheckIn:Read_Iridium] Stopped
2020-12-11T23:54:29.891Z,1607730869.891 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-11T23:54:29.891Z,1607730869.891 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-11T23:54:30.359Z,1607730870.359 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-11T23:54:30.359Z,1607730870.359 [NAL9602] No Fault, FailCount= 1
2020-12-11T23:54:59.768Z,1607730899.768 [NAL9602](INFO): Powering up NAL9602
2020-12-11T23:55:10.682Z,1607730910.682 [NAL9602](INFO): NAL9602 initialized
2020-12-11T23:55:41.781Z,1607730941.781 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-11T23:59:30.636Z,1607731170.636 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-11T23:59:30.637Z,1607731170.637 [Default:CheckIn:C.Wait] Stopped
2020-12-11T23:59:30.637Z,1607731170.637 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-11T23:59:30.637Z,1607731170.637 [Default:CheckIn:D] Running Loop=1
2020-12-11T23:59:31.035Z,1607731171.035 [Default:CheckIn:D] Stopped
2020-12-11T23:59:31.035Z,1607731171.035 [Default:CheckIn:E] Running Loop=1
2020-12-11T23:59:31.472Z,1607731171.472 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.247333 min 
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn:E] Stopped
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn] Stopped
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn](INFO): Running loop #5
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn] Running Loop=5
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-11T23:59:31.473Z,1607731171.473 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-11T23:59:33.445Z,1607731173.445 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235934.00,A,3648.16441,N,12147.21630,W,0.019,0.00,111220,,,A*77
2020-12-11T23:59:33.447Z,1607731173.447 [NAL9602](INFO): GPS fix at 20201211T235934: (36.802740, -121.786938)
2020-12-11T23:59:33.458Z,1607731173.458 [Default:CheckIn:Read_GPS] Stopped
2020-12-11T23:59:33.458Z,1607731173.458 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-11T23:59:35.456Z,1607731175.456 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-11T23:59:41.855Z,1607731181.855 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201211T233833/Courier0012.lzma
2020-12-11T23:59:42.856Z,1607731182.856 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0012.lzma.bak
2020-12-11T23:59:42.856Z,1607731182.856 [DataOverHttps](INFO): SBD MOMSN=13491714
2020-12-11T23:59:59.042Z,1607731199.042 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201211T233833/Express0013.lzma
2020-12-12T00:00:00.044Z,1607731200.044 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0013.lzma.bak
2020-12-12T00:00:00.044Z,1607731200.044 [DataOverHttps](INFO): SBD MOMSN=13491720
2020-12-12T00:00:01.734Z,1607731201.734 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:00:01.734Z,1607731201.734 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:00:01.734Z,1607731201.734 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:00:06.159Z,1607731206.159 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:05:02.507Z,1607731502.507 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:05:02.507Z,1607731502.507 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:05:02.507Z,1607731502.507 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:05:02.507Z,1607731502.507 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:05:02.934Z,1607731502.934 [Default:CheckIn:D] Stopped
2020-12-12T00:05:02.934Z,1607731502.934 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:05:03.380Z,1607731503.380 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.778518 min 
2020-12-12T00:05:03.380Z,1607731503.380 [Default:CheckIn:E] Stopped
2020-12-12T00:05:03.380Z,1607731503.380 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:05:03.380Z,1607731503.380 [Default:CheckIn] Stopped
2020-12-12T00:05:03.380Z,1607731503.380 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:05:03.381Z,1607731503.381 [Default:CheckIn](INFO): Running loop #6
2020-12-12T00:05:03.381Z,1607731503.381 [Default:CheckIn] Running Loop=6
2020-12-12T00:05:03.381Z,1607731503.381 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:05:03.381Z,1607731503.381 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:05:05.321Z,1607731505.321 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000506.00,A,3648.16551,N,12147.21665,W,0.019,0.00,121220,,,A*7D
2020-12-12T00:05:05.324Z,1607731505.324 [NAL9602](INFO): GPS fix at 20201212T000506: (36.802759, -121.786944)
2020-12-12T00:05:05.407Z,1607731505.407 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:05:05.407Z,1607731505.407 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:05:17.962Z,1607731517.962 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201211T233833/Courier0015.lzma
2020-12-12T00:05:18.964Z,1607731518.964 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0015.lzma.bak
2020-12-12T00:05:18.964Z,1607731518.964 [DataOverHttps](INFO): SBD MOMSN=13491741
2020-12-12T00:05:31.269Z,1607731531.269 [NAL9602](INFO): SBD MO Status=2, MOMSN=12143, MT Status=2, MTMSN=0
2020-12-12T00:05:31.269Z,1607731531.269 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T00:05:46.776Z,1607731546.776 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20201211T233833/Express0016.lzma
2020-12-12T00:05:47.429Z,1607731547.429 [NAL9602](INFO): SBD MO Status=0, MOMSN=12143, MT Status=0, MTMSN=0
2020-12-12T00:05:47.429Z,1607731547.429 [NAL9602](INFO): No messages in MT queue
2020-12-12T00:05:47.777Z,1607731547.777 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0016.lzma.bak
2020-12-12T00:05:47.777Z,1607731547.777 [DataOverHttps](INFO): SBD MOMSN=13491744
2020-12-12T00:05:49.865Z,1607731549.865 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:05:49.866Z,1607731549.866 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:05:49.866Z,1607731549.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:06:18.204Z,1607731578.204 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:08:11.226Z,1607731691.226 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2020-12-12T00:08:11.226Z,1607731691.226 [RDI_Pathfinder] Communications Fault, FailCount= 1
2020-12-12T00:08:11.226Z,1607731691.226 [RDI_Pathfinder](ERROR): Communications Fault
2020-12-12T00:08:11.320Z,1607731691.320 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2020-12-12T00:08:11.679Z,1607731691.679 [RDI_Pathfinder](INFO): Powering down
2020-12-12T00:08:12.475Z,1607731692.475 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2020-12-12T00:08:12.475Z,1607731692.475 [RDI_Pathfinder] No Fault, FailCount= 1
2020-12-12T00:10:50.666Z,1607731850.666 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:10:50.667Z,1607731850.667 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:10:50.667Z,1607731850.667 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:10:50.667Z,1607731850.667 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:10:51.011Z,1607731851.011 [Default:CheckIn:D] Stopped
2020-12-12T00:10:51.011Z,1607731851.011 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:10:51.436Z,1607731851.436 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.580273 min 
2020-12-12T00:10:51.436Z,1607731851.436 [Default:CheckIn:E] Stopped
2020-12-12T00:10:51.436Z,1607731851.436 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn] Stopped
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn](INFO): Running loop #7
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn] Running Loop=7
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:10:51.437Z,1607731851.437 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:10:53.405Z,1607731853.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001055.00,A,3648.17098,N,12147.28508,W,0.253,291.56,121220,,,A*7A
2020-12-12T00:10:53.408Z,1607731853.408 [NAL9602](INFO): GPS fix at 20201212T001055: (36.802850, -121.788085)
2020-12-12T00:10:53.424Z,1607731853.424 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:10:53.424Z,1607731853.424 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:11:05.666Z,1607731865.666 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201211T233833/Courier0018.lzma
2020-12-12T00:11:06.668Z,1607731866.668 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0018.lzma.bak
2020-12-12T00:11:06.668Z,1607731866.668 [DataOverHttps](INFO): SBD MOMSN=13491810
2020-12-12T00:11:34.502Z,1607731894.502 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20201211T233833/Express0019.lzma
2020-12-12T00:11:35.504Z,1607731895.504 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0019.lzma.bak
2020-12-12T00:11:35.504Z,1607731895.504 [DataOverHttps](INFO): SBD MOMSN=13491813
2020-12-12T00:11:37.764Z,1607731897.764 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:11:37.764Z,1607731897.764 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:11:37.765Z,1607731897.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:13:27.005Z,1607732007.005 [NAL9602](INFO): SBD MO Status=2, MOMSN=12144, MT Status=2, MTMSN=0
2020-12-12T00:13:27.005Z,1607732007.005 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T00:13:53.276Z,1607732033.276 [NAL9602](INFO): SBD MO Status=2, MOMSN=12144, MT Status=2, MTMSN=0
2020-12-12T00:13:53.276Z,1607732033.276 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T00:14:15.065Z,1607732055.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=12144, MT Status=2, MTMSN=0
2020-12-12T00:14:15.065Z,1607732055.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T00:14:57.761Z,1607732097.761 [NAL9602](INFO): SBD MO Status=2, MOMSN=12144, MT Status=2, MTMSN=0
2020-12-12T00:14:57.761Z,1607732097.761 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T00:15:55.530Z,1607732155.530 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T00:16:26.239Z,1607732186.239 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:16:38.452Z,1607732198.452 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:16:38.452Z,1607732198.452 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:16:38.452Z,1607732198.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:16:38.453Z,1607732198.453 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:16:38.803Z,1607732198.803 [Default:CheckIn:D] Stopped
2020-12-12T00:16:38.803Z,1607732198.803 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:16:39.233Z,1607732199.233 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.376794 min 
2020-12-12T00:16:39.233Z,1607732199.233 [Default:CheckIn:E] Stopped
2020-12-12T00:16:39.233Z,1607732199.233 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:16:39.233Z,1607732199.233 [Default:CheckIn] Stopped
2020-12-12T00:16:39.233Z,1607732199.233 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:16:39.234Z,1607732199.234 [Default:CheckIn](INFO): Running loop #8
2020-12-12T00:16:39.234Z,1607732199.234 [Default:CheckIn] Running Loop=8
2020-12-12T00:16:39.234Z,1607732199.234 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:16:39.254Z,1607732199.254 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:16:41.195Z,1607732201.195 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001643.00,A,3648.16789,N,12147.28103,W,0.156,291.56,121220,,,A*74
2020-12-12T00:16:41.198Z,1607732201.198 [NAL9602](INFO): GPS fix at 20201212T001643: (36.802798, -121.788017)
2020-12-12T00:16:41.222Z,1607732201.222 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:16:41.238Z,1607732201.238 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:16:50.248Z,1607732210.248 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20201211T233833/Courier0021.lzma
2020-12-12T00:16:51.248Z,1607732211.248 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0021.lzma.bak
2020-12-12T00:16:51.248Z,1607732211.248 [DataOverHttps](INFO): SBD MOMSN=13491830
2020-12-12T00:17:07.486Z,1607732227.486 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201211T233833/Express0022.lzma
2020-12-12T00:17:08.488Z,1607732228.488 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0022.lzma.bak
2020-12-12T00:17:08.488Z,1607732228.488 [DataOverHttps](INFO): SBD MOMSN=13491833
2020-12-12T00:17:10.298Z,1607732230.298 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:17:10.298Z,1607732230.298 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:17:10.298Z,1607732230.298 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:17:11.896Z,1607732231.896 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-12T00:17:11.974Z,1607732231.974 [NAL9602](FAULT): received: 
+CSQ:0

OK144, 2, 0, 0, 0

OK
2020-12-12T00:17:11.974Z,1607732231.974 [NAL9602] Data Fault, FailCount= 1
2020-12-12T00:17:11.974Z,1607732231.974 [NAL9602](ERROR): Data Fault
2020-12-12T00:17:12.080Z,1607732232.080 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T00:17:12.315Z,1607732232.315 [NAL9602](INFO): Powering down
2020-12-12T00:17:12.703Z,1607732232.703 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-12-12T00:17:12.703Z,1607732232.703 [NAL9602] Hardware Fault, FailCount= 1
2020-12-12T00:17:12.703Z,1607732232.703 [NAL9602](ERROR): Hardware Fault
2020-12-12T00:17:13.197Z,1607732233.197 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T00:17:13.197Z,1607732233.197 [NAL9602] No Fault, FailCount= 1
2020-12-12T00:17:42.611Z,1607732262.611 [NAL9602](INFO): Powering up NAL9602
2020-12-12T00:17:53.511Z,1607732273.511 [NAL9602](INFO): NAL9602 initialized
2020-12-12T00:18:24.254Z,1607732304.254 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-12-12T00:18:24.255Z,1607732304.255 [DropWeight] Hardware Fault, FailCount= 1
2020-12-12T00:18:24.255Z,1607732304.255 [DropWeight](ERROR): Hardware Fault
2020-12-12T00:18:24.401Z,1607732304.401 [CommandLine](FAULT): Scheduling is paused
2020-12-12T00:18:24.402Z,1607732304.402 [CBIT](INFO): Critical error at 20201212T001824
2020-12-12T00:18:24.421Z,1607732304.421 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-12-12T00:18:24.421Z,1607732304.421 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-12-12T00:18:24.652Z,1607732304.652 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:18:24.737Z,1607732304.737 [CBIT](INFO): Critical error at 20201212T001824
2020-12-12T00:22:10.947Z,1607732530.947 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:22:10.947Z,1607732530.947 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:22:10.947Z,1607732530.947 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:22:10.947Z,1607732530.947 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:22:11.347Z,1607732531.347 [Default:CheckIn:D] Stopped
2020-12-12T00:22:11.347Z,1607732531.347 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.919198 min 
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn:E] Stopped
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn] Stopped
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn](INFO): Running loop #9
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn] Running Loop=9
2020-12-12T00:22:11.815Z,1607732531.815 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:22:11.816Z,1607732531.816 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:22:13.761Z,1607732533.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002214.00,A,3648.16522,N,12147.28094,W,0.136,0.00,121220,,,A*72
2020-12-12T00:22:13.763Z,1607732533.763 [NAL9602](INFO): GPS fix at 20201212T002214: (36.802754, -121.788016)
2020-12-12T00:22:13.790Z,1607732533.790 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:22:13.791Z,1607732533.791 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:22:16.291Z,1607732536.291 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T00:22:25.582Z,1607732545.582 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20201211T233833/Courier0024.lzma
2020-12-12T00:22:26.580Z,1607732546.580 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0024.lzma.bak
2020-12-12T00:22:26.581Z,1607732546.581 [DataOverHttps](INFO): SBD MOMSN=13491897
2020-12-12T00:22:46.414Z,1607732566.414 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20201211T233833/Express0025.lzma
2020-12-12T00:22:46.724Z,1607732566.724 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:22:47.771Z,1607732567.771 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0025.lzma.bak
2020-12-12T00:22:47.771Z,1607732567.771 [DataOverHttps](INFO): SBD MOMSN=13491902
2020-12-12T00:22:49.198Z,1607732569.198 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:22:49.198Z,1607732569.198 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:22:49.198Z,1607732569.198 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:25:25.528Z,1607732725.528 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-12-12T00:27:49.829Z,1607732869.829 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:27:49.829Z,1607732869.829 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:27:49.829Z,1607732869.829 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:27:49.829Z,1607732869.829 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:27:50.239Z,1607732870.239 [Default:CheckIn:D] Stopped
2020-12-12T00:27:50.239Z,1607732870.239 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:27:50.625Z,1607732870.625 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.567399 min 
2020-12-12T00:27:50.625Z,1607732870.625 [Default:CheckIn:E] Stopped
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn] Stopped
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn](INFO): Running loop #10
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn] Running Loop=10
2020-12-12T00:27:50.626Z,1607732870.626 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:27:50.627Z,1607732870.627 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:27:52.641Z,1607732872.641 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002753.00,A,3648.16840,N,12147.29065,W,0.991,260.11,121220,,,A*73
2020-12-12T00:27:52.643Z,1607732872.643 [NAL9602](INFO): GPS fix at 20201212T002753: (36.802807, -121.788178)
2020-12-12T00:27:52.667Z,1607732872.667 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:27:52.667Z,1607732872.667 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:28:00.361Z,1607732880.361 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201211T233833/Courier0027.lzma
2020-12-12T00:28:01.364Z,1607732881.364 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0027.lzma.bak
2020-12-12T00:28:01.364Z,1607732881.364 [DataOverHttps](INFO): SBD MOMSN=13491924
2020-12-12T00:28:20.242Z,1607732900.242 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20201211T233833/Express0028.lzma
2020-12-12T00:28:21.244Z,1607732901.244 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0028.lzma.bak
2020-12-12T00:28:21.244Z,1607732901.244 [DataOverHttps](INFO): SBD MOMSN=13491927
2020-12-12T00:28:23.469Z,1607732903.469 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:28:23.469Z,1607732903.469 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:28:23.470Z,1607732903.470 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:32:54.607Z,1607733174.607 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T00:33:24.116Z,1607733204.116 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:33:24.116Z,1607733204.116 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:33:24.117Z,1607733204.117 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:33:24.117Z,1607733204.117 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:33:24.529Z,1607733204.529 [Default:CheckIn:D] Stopped
2020-12-12T00:33:24.529Z,1607733204.529 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:33:24.539Z,1607733204.539 [CBIT](INFO): Clearing failed state for component DropWeight
2020-12-12T00:33:24.539Z,1607733204.539 [DropWeight] No Fault, FailCount= 1
2020-12-12T00:33:24.919Z,1607733204.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.138908 min 
2020-12-12T00:33:24.919Z,1607733204.919 [Default:CheckIn:E] Stopped
2020-12-12T00:33:24.919Z,1607733204.919 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:33:24.919Z,1607733204.919 [Default:CheckIn] Stopped
2020-12-12T00:33:24.919Z,1607733204.919 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:33:24.920Z,1607733204.920 [Default:CheckIn](INFO): Running loop #11
2020-12-12T00:33:24.920Z,1607733204.920 [Default:CheckIn] Running Loop=11
2020-12-12T00:33:24.920Z,1607733204.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:33:24.920Z,1607733204.920 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:33:25.307Z,1607733205.307 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:33:26.933Z,1607733206.933 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003329.00,A,3648.17515,N,12147.27074,W,4.335,55.31,121220,,,A*4F
2020-12-12T00:33:26.935Z,1607733206.935 [NAL9602](INFO): GPS fix at 20201212T003329: (36.802919, -121.787846)
2020-12-12T00:33:26.947Z,1607733206.947 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:33:26.947Z,1607733206.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:33:34.140Z,1607733214.140 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201211T233833/Courier0030.lzma
2020-12-12T00:33:35.140Z,1607733215.140 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0030.lzma.bak
2020-12-12T00:33:35.141Z,1607733215.141 [DataOverHttps](INFO): SBD MOMSN=13491983
2020-12-12T00:33:51.470Z,1607733231.470 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20201211T233833/Express0031.lzma
2020-12-12T00:33:52.792Z,1607733232.792 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0031.lzma.bak
2020-12-12T00:33:52.793Z,1607733232.793 [DataOverHttps](INFO): SBD MOMSN=13491986
2020-12-12T00:33:53.998Z,1607733233.998 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:33:53.998Z,1607733233.998 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:33:53.998Z,1607733233.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:33:57.625Z,1607733237.625 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-12T00:33:57.698Z,1607733237.698 [NAL9602](FAULT): received: 
+CSQ:0

OK144, 2, 0, 0, 0

OK
2020-12-12T00:33:57.698Z,1607733237.698 [NAL9602] Data Fault, FailCount= 1
2020-12-12T00:33:57.698Z,1607733237.698 [NAL9602](ERROR): Data Fault
2020-12-12T00:33:57.762Z,1607733237.762 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T00:33:58.027Z,1607733238.027 [NAL9602](INFO): Powering down
2020-12-12T00:33:58.918Z,1607733238.918 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T00:33:58.918Z,1607733238.918 [NAL9602] No Fault, FailCount= 1
2020-12-12T00:34:28.063Z,1607733268.063 [NAL9602](INFO): Powering up NAL9602
2020-12-12T00:34:38.976Z,1607733278.976 [NAL9602](INFO): NAL9602 initialized
2020-12-12T00:35:10.083Z,1607733310.083 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:38:54.809Z,1607733534.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:38:54.809Z,1607733534.809 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:38:54.809Z,1607733534.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:38:54.810Z,1607733534.810 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:38:55.198Z,1607733535.198 [Default:CheckIn:D] Stopped
2020-12-12T00:38:55.198Z,1607733535.198 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:38:55.611Z,1607733535.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.650049 min 
2020-12-12T00:38:55.611Z,1607733535.611 [Default:CheckIn:E] Stopped
2020-12-12T00:38:55.611Z,1607733535.611 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:38:55.611Z,1607733535.611 [Default:CheckIn] Stopped
2020-12-12T00:38:55.611Z,1607733535.611 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:38:55.612Z,1607733535.612 [Default:CheckIn](INFO): Running loop #12
2020-12-12T00:38:55.612Z,1607733535.612 [Default:CheckIn] Running Loop=12
2020-12-12T00:38:55.612Z,1607733535.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:38:55.612Z,1607733535.612 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:38:58.823Z,1607733538.823 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T00:43:55.956Z,1607733835.956 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-12T00:38:55.6Z
2020-12-12T00:43:55.956Z,1607733835.956 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:43:55.957Z,1607733835.957 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:44:03.308Z,1607733843.308 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201211T233833/Courier0033.lzma
2020-12-12T00:44:04.304Z,1607733844.304 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0033.lzma.bak
2020-12-12T00:44:04.304Z,1607733844.304 [DataOverHttps](INFO): SBD MOMSN=13492056
2020-12-12T00:44:24.410Z,1607733864.410 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20201211T233833/Express0034.lzma
2020-12-12T00:44:25.412Z,1607733865.412 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0034.lzma.bak
2020-12-12T00:44:25.412Z,1607733865.412 [DataOverHttps](INFO): SBD MOMSN=13492058
2020-12-12T00:44:27.531Z,1607733867.531 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:44:27.640Z,1607733867.640 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:44:27.640Z,1607733867.640 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:44:27.640Z,1607733867.640 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:48:49.519Z,1607734129.519 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-12T00:48:49.522Z,1607734129.522 [BPC1](INFO): Received data from all battery sticks.
2020-12-12T00:49:28.321Z,1607734168.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:49:28.321Z,1607734168.321 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:49:28.321Z,1607734168.321 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:49:28.321Z,1607734168.321 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:49:28.747Z,1607734168.747 [Default:CheckIn:D] Stopped
2020-12-12T00:49:28.747Z,1607734168.747 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.209204 min 
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn:E] Stopped
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn] Stopped
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn](INFO): Running loop #13
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn] Running Loop=13
2020-12-12T00:49:29.119Z,1607734169.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:49:29.120Z,1607734169.120 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T00:49:30.716Z,1607734170.716 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-12T00:49:30.716Z,1607734170.716 [NAL9602] Data Fault, FailCount= 1
2020-12-12T00:49:30.716Z,1607734170.716 [NAL9602](ERROR): Data Fault
2020-12-12T00:49:30.756Z,1607734170.756 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T00:49:31.125Z,1607734171.125 [NAL9602](INFO): Powering down
2020-12-12T00:49:31.527Z,1607734171.527 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-12-12T00:49:31.527Z,1607734171.527 [NAL9602] Hardware Fault, FailCount= 1
2020-12-12T00:49:31.527Z,1607734171.527 [NAL9602](ERROR): Hardware Fault
2020-12-12T00:49:31.960Z,1607734171.960 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T00:49:31.960Z,1607734171.960 [NAL9602] No Fault, FailCount= 1
2020-12-12T00:50:01.420Z,1607734201.420 [NAL9602](INFO): Powering up NAL9602
2020-12-12T00:50:12.344Z,1607734212.344 [NAL9602](INFO): NAL9602 initialized
2020-12-12T00:54:29.454Z,1607734469.454 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-12T00:49:29.1Z
2020-12-12T00:54:29.454Z,1607734469.454 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T00:54:29.455Z,1607734469.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T00:54:37.841Z,1607734477.841 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20201211T233833/Courier0036.lzma
2020-12-12T00:54:38.844Z,1607734478.844 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0036.lzma.bak
2020-12-12T00:54:38.844Z,1607734478.844 [DataOverHttps](INFO): SBD MOMSN=13492134
2020-12-12T00:54:55.054Z,1607734495.054 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20201211T233833/Express0037.lzma
2020-12-12T00:54:56.056Z,1607734496.056 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0037.lzma.bak
2020-12-12T00:54:56.056Z,1607734496.056 [DataOverHttps](INFO): SBD MOMSN=13492136
2020-12-12T00:54:57.767Z,1607734497.767 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T00:54:57.767Z,1607734497.767 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T00:54:57.767Z,1607734497.767 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T00:55:15.532Z,1607734515.532 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T00:55:46.233Z,1607734546.233 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T00:59:58.351Z,1607734798.351 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T00:59:58.351Z,1607734798.351 [Default:CheckIn:C.Wait] Stopped
2020-12-12T00:59:58.351Z,1607734798.351 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T00:59:58.351Z,1607734798.351 [Default:CheckIn:D] Running Loop=1
2020-12-12T00:59:58.769Z,1607734798.769 [Default:CheckIn:D] Stopped
2020-12-12T00:59:58.769Z,1607734798.769 [Default:CheckIn:E] Running Loop=1
2020-12-12T00:59:59.162Z,1607734799.162 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.709570 min 
2020-12-12T00:59:59.162Z,1607734799.162 [Default:CheckIn:E] Stopped
2020-12-12T00:59:59.162Z,1607734799.162 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T00:59:59.162Z,1607734799.162 [Default:CheckIn] Stopped
2020-12-12T00:59:59.163Z,1607734799.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T00:59:59.163Z,1607734799.163 [Default:CheckIn](INFO): Running loop #14
2020-12-12T00:59:59.163Z,1607734799.163 [Default:CheckIn] Running Loop=14
2020-12-12T00:59:59.163Z,1607734799.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T00:59:59.163Z,1607734799.163 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T01:00:31.932Z,1607734831.932 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-12T01:00:32.014Z,1607734832.014 [NAL9602](FAULT): received: 
+CSQ:0

OK144, 2, 0, 0, 0

OK
2020-12-12T01:00:32.014Z,1607734832.014 [NAL9602] Data Fault, FailCount= 2
2020-12-12T01:00:32.014Z,1607734832.014 [NAL9602](ERROR): Data Fault
2020-12-12T01:00:32.040Z,1607734832.040 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T01:00:32.336Z,1607734832.336 [NAL9602](INFO): Powering down
2020-12-12T01:00:32.740Z,1607734832.740 [NAL9602](FAULT): LCB fault:  Invalid Command. Hardware Overcurrent Shutdown. Software Overcurrent. Current Limiter Activated.
2020-12-12T01:00:32.740Z,1607734832.740 [NAL9602] Hardware Fault, FailCount= 2
2020-12-12T01:00:32.740Z,1607734832.740 [NAL9602](ERROR): Hardware Fault
2020-12-12T01:00:33.194Z,1607734833.194 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T01:00:33.194Z,1607734833.194 [NAL9602] No Fault, FailCount= 2
2020-12-12T01:01:02.654Z,1607734862.654 [NAL9602](INFO): Powering up NAL9602
2020-12-12T01:01:13.551Z,1607734873.551 [NAL9602](INFO): NAL9602 initialized
2020-12-12T01:01:14.364Z,1607734874.364 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-12T01:01:14.364Z,1607734874.364 [NAL9602] Data Fault, FailCount= 3
2020-12-12T01:01:14.364Z,1607734874.364 [NAL9602](ERROR): Data Fault
2020-12-12T01:01:14.395Z,1607734874.395 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T01:01:14.759Z,1607734874.759 [NAL9602](INFO): Powering down
2020-12-12T01:01:15.590Z,1607734875.590 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T01:01:15.591Z,1607734875.591 [NAL9602] No Fault, FailCount= 3
2020-12-12T01:01:45.060Z,1607734905.060 [NAL9602](INFO): Powering up NAL9602
2020-12-12T01:01:55.972Z,1607734915.972 [NAL9602](INFO): NAL9602 initialized
2020-12-12T01:03:26.067Z,1607735006.067 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-12-12T01:03:26.067Z,1607735006.067 [DropWeight] Hardware Fault, FailCount= 1
2020-12-12T01:03:26.067Z,1607735006.067 [DropWeight](ERROR): Hardware Fault
2020-12-12T01:03:26.114Z,1607735006.114 [CBIT](INFO): Critical error at 20201212T010326
2020-12-12T01:03:26.117Z,1607735006.117 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-12-12T01:03:26.117Z,1607735006.117 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-12-12T01:03:26.507Z,1607735006.507 [CBIT](INFO): Critical error at 20201212T010326
2020-12-12T01:04:59.420Z,1607735099.420 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-12T00:59:59.2Z
2020-12-12T01:04:59.420Z,1607735099.420 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T01:04:59.421Z,1607735099.421 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T01:05:03.440Z,1607735103.440 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T01:05:06.734Z,1607735106.734 [DataOverHttps](INFO): Sending 116 bytes from file Logs/20201211T233833/Courier0039.lzma
2020-12-12T01:05:07.736Z,1607735107.736 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0039.lzma.bak
2020-12-12T01:05:07.736Z,1607735107.736 [DataOverHttps](INFO): SBD MOMSN=13492192
2020-12-12T01:05:23.926Z,1607735123.926 [DataOverHttps](INFO): Sending 341 bytes from file Logs/20201211T233833/Express0040.lzma
2020-12-12T01:05:24.928Z,1607735124.928 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0040.lzma.bak
2020-12-12T01:05:24.928Z,1607735124.928 [DataOverHttps](INFO): SBD MOMSN=13492196
2020-12-12T01:05:26.489Z,1607735126.489 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T01:05:26.489Z,1607735126.489 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T01:05:26.490Z,1607735126.490 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T01:05:34.162Z,1607735134.162 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T01:07:08.114Z,1607735228.114 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2020-12-12T01:07:10.123Z,1607735230.123 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.803387
2020-12-12T01:07:20.931Z,1607735240.931 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002900
2020-12-12T01:10:27.063Z,1607735427.063 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-12T01:10:27.063Z,1607735427.063 [Default:CheckIn:C.Wait] Stopped
2020-12-12T01:10:27.063Z,1607735427.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T01:10:27.063Z,1607735427.063 [Default:CheckIn:D] Running Loop=1
2020-12-12T01:10:27.523Z,1607735427.523 [Default:CheckIn:D] Stopped
2020-12-12T01:10:27.523Z,1607735427.523 [Default:CheckIn:E] Running Loop=1
2020-12-12T01:10:27.874Z,1607735427.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.188721 min 
2020-12-12T01:10:27.874Z,1607735427.874 [Default:CheckIn:E] Stopped
2020-12-12T01:10:27.874Z,1607735427.874 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-12T01:10:27.874Z,1607735427.874 [Default:CheckIn] Stopped
2020-12-12T01:10:27.874Z,1607735427.874 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T01:10:27.875Z,1607735427.875 [Default:CheckIn](INFO): Running loop #15
2020-12-12T01:10:27.875Z,1607735427.875 [Default:CheckIn] Running Loop=15
2020-12-12T01:10:27.875Z,1607735427.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-12T01:10:27.875Z,1607735427.875 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-12T01:11:00.579Z,1607735460.579 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-12T01:11:00.654Z,1607735460.654 [NAL9602](FAULT): received: 
+CSQ:0

OK144, 2, 0, 0, 0

OK
2020-12-12T01:11:00.654Z,1607735460.654 [NAL9602] Data Fault, FailCount= 4
2020-12-12T01:11:00.654Z,1607735460.654 [NAL9602](ERROR): Data Fault
2020-12-12T01:11:00.709Z,1607735460.709 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-12T01:11:00.991Z,1607735460.991 [NAL9602](INFO): Powering down
2020-12-12T01:11:01.841Z,1607735461.841 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-12T01:11:01.841Z,1607735461.841 [NAL9602] No Fault, FailCount= 4
2020-12-12T01:11:31.292Z,1607735491.292 [NAL9602](INFO): Powering up NAL9602
2020-12-12T01:11:42.195Z,1607735502.195 [NAL9602](INFO): NAL9602 initialized
2020-12-12T01:14:40.365Z,1607735680.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=12144, MT Status=2, MTMSN=0
2020-12-12T01:14:40.365Z,1607735680.365 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-12T01:15:28.226Z,1607735728.226 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-12T01:10:27.9Z
2020-12-12T01:15:28.226Z,1607735728.226 [Default:CheckIn:Read_GPS] Stopped
2020-12-12T01:15:28.227Z,1607735728.227 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-12T01:15:31.935Z,1607735731.935 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-12T01:15:39.886Z,1607735739.886 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201211T233833/Courier0042.lzma
2020-12-12T01:15:40.888Z,1607735740.888 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Courier0042.lzma.bak
2020-12-12T01:15:40.888Z,1607735740.888 [DataOverHttps](INFO): SBD MOMSN=13492262
2020-12-12T01:16:02.639Z,1607735762.639 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-12T01:16:07.863Z,1607735767.863 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20201211T233833/Express0043.lzma
2020-12-12T01:16:08.864Z,1607735768.864 [DataOverHttps](INFO): Moved sent file to Logs/20201211T233833/Express0043.lzma.bak
2020-12-12T01:16:08.864Z,1607735768.864 [DataOverHttps](INFO): SBD MOMSN=13492264
2020-12-12T01:16:11.185Z,1607735771.185 [Default:CheckIn:Read_Iridium] Stopped
2020-12-12T01:16:11.186Z,1607735771.186 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-12T01:16:11.194Z,1607735771.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-12T01:18:03.500Z,1607735883.500 [CommandLine](IMPORTANT): got command quit
2020-12-12T01:18:04.510Z,1607735884.510 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:04.511Z,1607735884.511 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:04.546Z,1607735884.546 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-12-12T01:18:04.547Z,1607735884.547 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:04.567Z,1607735884.567 [CommandLine](INFO): Join timeout helper Thread ID is 3456
2020-12-12T01:18:04.590Z,1607735884.590 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-12-12T01:18:04.590Z,1607735884.590 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:04.591Z,1607735884.591 [NavChartDb](INFO): Join timeout helper Thread ID is 3457
2020-12-12T01:18:04.803Z,1607735884.803 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:04.803Z,1607735884.803 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:04.814Z,1607735884.814 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2020-12-12T01:18:04.814Z,1607735884.814 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:04.815Z,1607735884.815 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3458
2020-12-12T01:18:05.138Z,1607735885.138 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:05.139Z,1607735885.139 [WetLabsUBAT](INFO): Powering down
2020-12-12T01:18:05.139Z,1607735885.139 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.154Z,1607735885.154 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-12-12T01:18:05.154Z,1607735885.154 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.155Z,1607735885.155 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3459
2020-12-12T01:18:05.168Z,1607735885.168 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:05.169Z,1607735885.169 [WetLabsBB2FL](INFO): Powering down
2020-12-12T01:18:05.171Z,1607735885.171 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.176Z,1607735885.176 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-12-12T01:18:05.176Z,1607735885.176 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.177Z,1607735885.177 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3460
2020-12-12T01:18:05.758Z,1607735885.758 [CTD_Seabird](INFO): Powering down
2020-12-12T01:18:05.770Z,1607735885.770 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:05.771Z,1607735885.771 [CTD_Seabird](INFO): Powering down
2020-12-12T01:18:05.782Z,1607735885.782 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.792Z,1607735885.792 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-12-12T01:18:05.792Z,1607735885.792 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.792Z,1607735885.792 [Radio_Surface](INFO): Join timeout helper Thread ID is 3461
2020-12-12T01:18:05.974Z,1607735885.974 [Radio_Surface](INFO): Powering down
2020-12-12T01:18:05.975Z,1607735885.975 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:05.976Z,1607735885.976 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.979Z,1607735885.979 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-12-12T01:18:05.979Z,1607735885.979 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:05.980Z,1607735885.980 [Onboard](INFO): Join timeout helper Thread ID is 3462
2020-12-12T01:18:06.179Z,1607735886.179 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-12-12T01:18:07.898Z,1607735887.898 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:07.899Z,1607735887.899 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:07.919Z,1607735887.919 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-12-12T01:18:07.919Z,1607735887.919 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:07.939Z,1607735887.939 [DataOverHttps](INFO): Join timeout helper Thread ID is 3463
2020-12-12T01:18:08.186Z,1607735888.186 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:08.186Z,1607735888.186 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.195Z,1607735888.195 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2020-12-12T01:18:08.195Z,1607735888.195 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.195Z,1607735888.195 [BackseatComponent](INFO): Join timeout helper Thread ID is 3464
2020-12-12T01:18:08.210Z,1607735888.210 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:08.211Z,1607735888.211 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.215Z,1607735888.215 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-12-12T01:18:08.215Z,1607735888.215 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.215Z,1607735888.215 [logger](INFO): Join timeout helper Thread ID is 3465
2020-12-12T01:18:08.216Z,1607735888.216 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:08.216Z,1607735888.216 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.217Z,1607735888.217 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-12-12T01:18:08.217Z,1607735888.217 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.218Z,1607735888.218 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-12-12T01:18:08.218Z,1607735888.218 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.218Z,1607735888.218 [controlThread](INFO): Join timeout helper Thread ID is 3466
2020-12-12T01:18:08.266Z,1607735888.266 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-12T01:18:08.267Z,1607735888.267 [controlThread](DEBUG): Uninitializing ControlThread
2020-12-12T01:18:08.267Z,1607735888.267 [AHRS_M2](INFO): Powering down
2020-12-12T01:18:08.339Z,1607735888.339 [NAL9602](INFO): Powering down
2020-12-12T01:18:08.410Z,1607735888.410 [RDI_Pathfinder](INFO): Powering down
2020-12-12T01:18:08.411Z,1607735888.411 [DAT](INFO): Powering down
2020-12-12T01:18:08.531Z,1607735888.531 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-12-12T01:18:08.532Z,1607735888.532 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-12-12T01:18:08.532Z,1607735888.532 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-12-12T01:18:08.533Z,1607735888.533 [MissionManager](INFO): Uninitializing Mission Default
2020-12-12T01:18:08.533Z,1607735888.533 [Default] Stopped
2020-12-12T01:18:08.533Z,1607735888.533 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-12T01:18:08.533Z,1607735888.533 [Default:B.GoToSurface] Stopped
2020-12-12T01:18:08.533Z,1607735888.533 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-12T01:18:08.533Z,1607735888.533 [Default:CheckIn] Stopped
2020-12-12T01:18:08.533Z,1607735888.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-12T01:18:08.533Z,1607735888.533 [Default:CheckIn:C.Wait] Stopped
2020-12-12T01:18:08.534Z,1607735888.534 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-12T01:18:08.537Z,1607735888.537 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-12-12T01:18:08.537Z,1607735888.537 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-12-12T01:18:08.537Z,1607735888.537 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-12-12T01:18:08.538Z,1607735888.538 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-12-12T01:18:08.538Z,1607735888.538 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-12-12T01:18:08.539Z,1607735888.539 [BuoyancyServo](INFO): Powering down
2020-12-12T01:18:08.550Z,1607735888.550 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-12-12T01:18:08.551Z,1607735888.551 [ElevatorServo](INFO): Powering down
2020-12-12T01:18:08.551Z,1607735888.551 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-12-12T01:18:08.552Z,1607735888.552 [MassServo](INFO): Powering down
2020-12-12T01:18:08.552Z,1607735888.552 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-12T01:18:08.552Z,1607735888.552 [RudderServo](INFO): Powering down
2020-12-12T01:18:08.553Z,1607735888.553 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-12-12T01:18:08.553Z,1607735888.553 [ThrusterServo](INFO): Powering down
2020-12-12T01:18:08.554Z,1607735888.554 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-12-12T01:18:08.555Z,1607735888.555 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-12-12T01:18:08.555Z,1607735888.555 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-12-12T01:18:08.555Z,1607735888.555 [CBIT](DEBUG): Powering off loads.
2020-12-12T01:18:08.566Z,1607735888.566 [CBIT](DEBUG): Disabling WDT.
2020-12-12T01:18:08.578Z,1607735888.578 [CBIT](DEBUG): Opening all GF detection circuits.
2020-12-12T01:18:08.579Z,1607735888.579 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.663Z,1607735888.663 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.666Z,1607735888.666 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.672Z,1607735888.672 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.716Z,1607735888.716 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.772Z,1607735888.772 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.774Z,1607735888.774 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.777Z,1607735888.777 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.785Z,1607735888.785 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-12T01:18:08.854Z,1607735888.854 [logger ThreadHandler](INFO): Thread cancelled.