2020-12-14T21:27:30.009Z,1607981250.009 [DataOverHttps](INFO): Received command:restart logs
2020-12-14T21:27:30.022Z,1607981250.022 [CommandLine](IMPORTANT): got command restart logs
2020-12-14T21:29:26.635Z,1607981366.635 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:29:26.635Z,1607981366.635 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:29:26.635Z,1607981366.635 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:29:26.636Z,1607981366.636 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:29:27.021Z,1607981367.021 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:29:27.021Z,1607981367.021 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:29:27.434Z,1607981367.434 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 17.303328 min 
2020-12-14T21:29:27.434Z,1607981367.434 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:29:27.434Z,1607981367.434 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:29:27.434Z,1607981367.434 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:29:27.434Z,1607981367.434 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:29:27.435Z,1607981367.435 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #4
2020-12-14T21:29:27.435Z,1607981367.435 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=4
2020-12-14T21:29:27.435Z,1607981367.435 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:29:27.435Z,1607981367.435 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:29:29.442Z,1607981369.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212929.00,A,4132.73352,N,07042.01325,W,7.387,169.44,141220,,,D*7B
2020-12-14T21:29:29.445Z,1607981369.445 [NAL9602](INFO): GPS fix at 20201214T212929: (41.545559, -70.700221)
2020-12-14T21:29:29.474Z,1607981369.474 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:29:29.475Z,1607981369.475 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:29:33.584Z,1607981373.584 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20201214T132831/Courier0177.lzma
2020-12-14T21:29:34.587Z,1607981374.587 [DataOverHttps](INFO): Moved sent file to Logs/20201214T132831/Courier0177.lzma.bak
2020-12-14T21:29:34.587Z,1607981374.587 [DataOverHttps](INFO): SBD MOMSN=66129
2020-12-14T21:29:43.629Z,1607981383.629 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20201214T212730/Courier0000.lzma
2020-12-14T21:29:43.982Z,1607981383.982 [NAL9602](INFO): SBD MO Status=1, MOMSN=12942, MT Status=0, MTMSN=0
2020-12-14T21:29:43.982Z,1607981383.982 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:29:44.631Z,1607981384.631 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0000.lzma.bak
2020-12-14T21:29:44.631Z,1607981384.631 [DataOverHttps](INFO): SBD MOMSN=66131
2020-12-14T21:29:53.672Z,1607981393.672 [DataOverHttps](INFO): Sending 52 bytes from file Logs/20201214T212730/Courier0003.lzma
2020-12-14T21:29:54.674Z,1607981394.674 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0003.lzma.bak
2020-12-14T21:29:54.674Z,1607981394.674 [DataOverHttps](INFO): SBD MOMSN=66135
2020-12-14T21:30:03.736Z,1607981403.736 [DataOverHttps](INFO): Sending 94 bytes from file Logs/20201214T132831/Express0178.lzma
2020-12-14T21:30:04.739Z,1607981404.739 [DataOverHttps](INFO): Moved sent file to Logs/20201214T132831/Express0178.lzma.bak
2020-12-14T21:30:04.739Z,1607981404.739 [DataOverHttps](INFO): SBD MOMSN=66137
2020-12-14T21:30:13.777Z,1607981413.777 [DataOverHttps](INFO): Sending 353 bytes from file Logs/20201214T212730/Express0001.lzma
2020-12-14T21:30:14.729Z,1607981414.729 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T21:30:14.778Z,1607981414.778 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0001.lzma.bak
2020-12-14T21:30:14.779Z,1607981414.779 [DataOverHttps](INFO): SBD MOMSN=66141
2020-12-14T21:30:23.816Z,1607981423.816 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20201214T212730/Express0004.lzma
2020-12-14T21:30:24.818Z,1607981424.818 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0004.lzma.bak
2020-12-14T21:30:24.819Z,1607981424.819 [DataOverHttps](INFO): SBD MOMSN=66145
2020-12-14T21:30:26.513Z,1607981426.513 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:30:26.514Z,1607981426.514 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:30:26.514Z,1607981426.514 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:30:41.459Z,1607981441.459 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-12-14T21:31:21.466Z,1607981481.466 [RDI_PathfinderUp](ERROR): only read 3 of 4 data items
2020-12-14T21:32:56.736Z,1607981576.736 [RDI_PathfinderUp](ERROR): only read 2 of 4 data items
2020-12-14T21:34:14.913Z,1607981654.913 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2020-12-14T21:34:14.916Z,1607981654.916 [BPC1](INFO): Received data from all battery sticks.
2020-12-14T21:35:27.226Z,1607981727.226 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:35:27.227Z,1607981727.227 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:35:27.227Z,1607981727.227 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:35:27.227Z,1607981727.227 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:35:27.687Z,1607981727.687 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:35:27.687Z,1607981727.687 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:35:28.042Z,1607981728.042 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 23.314429 min 
2020-12-14T21:35:28.042Z,1607981728.042 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:35:28.042Z,1607981728.042 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:35:28.042Z,1607981728.042 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:35:28.043Z,1607981728.043 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:35:28.043Z,1607981728.043 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #5
2020-12-14T21:35:28.043Z,1607981728.043 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=5
2020-12-14T21:35:28.043Z,1607981728.043 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:35:28.043Z,1607981728.043 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:35:30.051Z,1607981730.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213530.00,A,4131.79474,N,07041.61385,W,13.004,164.28,141220,,,A*44
2020-12-14T21:35:30.053Z,1607981730.053 [NAL9602](INFO): GPS fix at 20201214T213530: (41.529912, -70.693564)
2020-12-14T21:35:30.110Z,1607981730.110 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:35:30.110Z,1607981730.110 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:35:34.105Z,1607981734.105 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20201214T212730/Courier0006.lzma
2020-12-14T21:35:35.100Z,1607981735.100 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0006.lzma.bak
2020-12-14T21:35:35.100Z,1607981735.100 [DataOverHttps](INFO): SBD MOMSN=66147
2020-12-14T21:35:44.145Z,1607981744.145 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20201214T212730/Express0007.lzma
2020-12-14T21:35:45.150Z,1607981745.150 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0007.lzma.bak
2020-12-14T21:35:45.150Z,1607981745.150 [DataOverHttps](INFO): SBD MOMSN=66150
2020-12-14T21:35:47.278Z,1607981747.278 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:35:47.278Z,1607981747.278 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:35:47.278Z,1607981747.278 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:35:50.392Z,1607981750.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=12943, MT Status=2, MTMSN=0
2020-12-14T21:35:50.392Z,1607981750.392 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-14T21:36:05.345Z,1607981765.345 [NAL9602](INFO): SBD MO Status=1, MOMSN=12943, MT Status=0, MTMSN=0
2020-12-14T21:36:05.345Z,1607981765.345 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:36:36.041Z,1607981796.041 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T21:38:33.630Z,1607981913.630 [RDI_PathfinderUp](ERROR): only read 1 of 4 data items
2020-12-14T21:40:47.771Z,1607982047.771 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:40:47.771Z,1607982047.771 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:40:47.771Z,1607982047.771 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:40:47.771Z,1607982047.771 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:40:48.161Z,1607982048.161 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:40:48.161Z,1607982048.161 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:40:48.587Z,1607982048.587 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 28.655660 min 
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #6
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=6
2020-12-14T21:40:48.588Z,1607982048.588 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:40:48.589Z,1607982048.589 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:40:50.581Z,1607982050.581 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214050.00,A,4131.23343,N,07040.56029,W,13.179,73.82,141220,,,A*70
2020-12-14T21:40:50.583Z,1607982050.583 [NAL9602](INFO): GPS fix at 20201214T214050: (41.520557, -70.676005)
2020-12-14T21:40:50.622Z,1607982050.622 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:40:50.622Z,1607982050.622 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:40:54.438Z,1607982054.438 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0009.lzma
2020-12-14T21:40:55.438Z,1607982055.438 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0009.lzma.bak
2020-12-14T21:40:55.439Z,1607982055.439 [DataOverHttps](INFO): SBD MOMSN=66154
2020-12-14T21:41:04.473Z,1607982064.473 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20201214T212730/Express0010.lzma
2020-12-14T21:41:05.474Z,1607982065.474 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0010.lzma.bak
2020-12-14T21:41:05.475Z,1607982065.474 [DataOverHttps](INFO): SBD MOMSN=66157
2020-12-14T21:41:07.155Z,1607982067.155 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:41:07.155Z,1607982067.155 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:41:07.155Z,1607982067.155 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:41:27.749Z,1607982087.749 [NAL9602](INFO): SBD MO Status=1, MOMSN=12944, MT Status=0, MTMSN=0
2020-12-14T21:41:27.749Z,1607982087.749 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:41:58.467Z,1607982118.467 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T21:44:52.192Z,1607982292.192 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-12-14T21:46:07.759Z,1607982367.759 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:46:07.759Z,1607982367.759 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:46:07.760Z,1607982367.760 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:46:07.760Z,1607982367.760 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:46:08.155Z,1607982368.155 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:46:08.155Z,1607982368.155 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:46:08.580Z,1607982368.580 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 33.988890 min 
2020-12-14T21:46:08.580Z,1607982368.580 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:46:08.580Z,1607982368.580 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:46:08.580Z,1607982368.580 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:46:08.585Z,1607982368.585 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:46:08.585Z,1607982368.585 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #7
2020-12-14T21:46:08.585Z,1607982368.585 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=7
2020-12-14T21:46:08.585Z,1607982368.585 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:46:08.585Z,1607982368.585 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:46:10.565Z,1607982370.565 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214610.00,A,4131.45784,N,07040.31929,W,0.175,29.60,141220,,,D*4D
2020-12-14T21:46:10.567Z,1607982370.567 [NAL9602](INFO): GPS fix at 20201214T214610: (41.524297, -70.671988)
2020-12-14T21:46:10.587Z,1607982370.587 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:46:10.587Z,1607982370.587 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:46:14.788Z,1607982374.788 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0012.lzma
2020-12-14T21:46:15.792Z,1607982375.792 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0012.lzma.bak
2020-12-14T21:46:15.799Z,1607982375.799 [DataOverHttps](INFO): SBD MOMSN=66161
2020-12-14T21:46:24.828Z,1607982384.828 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20201214T212730/Express0013.lzma
2020-12-14T21:46:25.830Z,1607982385.830 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0013.lzma.bak
2020-12-14T21:46:25.831Z,1607982385.831 [DataOverHttps](INFO): SBD MOMSN=66164
2020-12-14T21:46:27.152Z,1607982387.152 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:46:27.152Z,1607982387.152 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:46:27.152Z,1607982387.152 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:46:27.533Z,1607982387.533 [NAL9602](INFO): SBD MO Status=1, MOMSN=12945, MT Status=0, MTMSN=0
2020-12-14T21:46:27.533Z,1607982387.533 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:46:58.233Z,1607982418.233 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T21:48:17.436Z,1607982497.436 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-12-14T21:51:27.755Z,1607982687.755 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:51:27.755Z,1607982687.755 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:51:27.755Z,1607982687.755 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:51:27.755Z,1607982687.755 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:51:28.125Z,1607982688.125 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:51:28.125Z,1607982688.125 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:51:28.542Z,1607982688.542 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 39.321724 min 
2020-12-14T21:51:28.542Z,1607982688.542 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:51:28.542Z,1607982688.542 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:51:28.542Z,1607982688.542 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:51:28.542Z,1607982688.542 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:51:28.543Z,1607982688.543 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #8
2020-12-14T21:51:28.543Z,1607982688.543 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=8
2020-12-14T21:51:28.543Z,1607982688.543 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:51:28.543Z,1607982688.543 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:51:30.550Z,1607982690.550 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215130.00,A,4131.45853,N,07040.31687,W,0.136,26.65,141220,,,D*4A
2020-12-14T21:51:30.553Z,1607982690.553 [NAL9602](INFO): GPS fix at 20201214T215130: (41.524309, -70.671948)
2020-12-14T21:51:30.573Z,1607982690.573 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:51:30.573Z,1607982690.573 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:51:34.145Z,1607982694.145 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0015.lzma
2020-12-14T21:51:35.148Z,1607982695.148 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0015.lzma.bak
2020-12-14T21:51:35.149Z,1607982695.149 [DataOverHttps](INFO): SBD MOMSN=66168
2020-12-14T21:51:44.197Z,1607982704.197 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20201214T212730/Express0016.lzma
2020-12-14T21:51:45.198Z,1607982705.198 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0016.lzma.bak
2020-12-14T21:51:45.199Z,1607982705.199 [DataOverHttps](INFO): SBD MOMSN=66171
2020-12-14T21:51:47.181Z,1607982707.181 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:51:47.181Z,1607982707.181 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:51:47.182Z,1607982707.182 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:51:49.187Z,1607982709.187 [NAL9602](INFO): SBD MO Status=1, MOMSN=12946, MT Status=0, MTMSN=0
2020-12-14T21:51:49.187Z,1607982709.187 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:52:19.889Z,1607982739.889 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T21:55:47.181Z,1607982947.181 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.249847
2020-12-14T21:55:57.225Z,1607982957.225 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003314
2020-12-14T21:56:47.809Z,1607983007.809 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T21:56:47.809Z,1607983007.809 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T21:56:47.809Z,1607983007.809 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T21:56:47.810Z,1607983007.810 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T21:56:48.193Z,1607983008.193 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T21:56:48.193Z,1607983008.193 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 44.656197 min 
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #9
2020-12-14T21:56:48.612Z,1607983008.612 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=9
2020-12-14T21:56:48.613Z,1607983008.613 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T21:56:48.613Z,1607983008.613 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T21:56:50.610Z,1607983010.610 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215650.00,A,4131.46245,N,07040.30502,W,0.058,159.25,141220,,,D*7E
2020-12-14T21:56:50.612Z,1607983010.612 [NAL9602](INFO): GPS fix at 20201214T215650: (41.524374, -70.671750)
2020-12-14T21:56:50.632Z,1607983010.632 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T21:56:50.632Z,1607983010.632 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T21:56:54.461Z,1607983014.461 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0018.lzma
2020-12-14T21:56:55.464Z,1607983015.464 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0018.lzma.bak
2020-12-14T21:56:55.464Z,1607983015.464 [DataOverHttps](INFO): SBD MOMSN=66175
2020-12-14T21:57:04.502Z,1607983024.502 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20201214T212730/Express0019.lzma
2020-12-14T21:57:05.503Z,1607983025.503 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0019.lzma.bak
2020-12-14T21:57:05.503Z,1607983025.503 [DataOverHttps](INFO): SBD MOMSN=66178
2020-12-14T21:57:07.190Z,1607983027.190 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T21:57:07.190Z,1607983027.190 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T21:57:07.190Z,1607983027.190 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T21:57:53.231Z,1607983073.231 [NAL9602](INFO): SBD MO Status=1, MOMSN=12947, MT Status=0, MTMSN=0
2020-12-14T21:57:53.231Z,1607983073.231 [NAL9602](INFO): No messages in MT queue
2020-12-14T21:58:23.936Z,1607983103.936 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T22:01:48.861Z,1607983308.861 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-12-14T22:02:07.792Z,1607983327.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T22:02:07.792Z,1607983327.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:02:07.792Z,1607983327.792 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:02:07.792Z,1607983327.792 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T22:02:08.191Z,1607983328.191 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T22:02:08.191Z,1607983328.191 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 49.989494 min 
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:02:08.608Z,1607983328.608 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #10
2020-12-14T22:02:08.609Z,1607983328.609 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=10
2020-12-14T22:02:08.609Z,1607983328.609 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:02:08.609Z,1607983328.609 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T22:02:10.596Z,1607983330.596 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220210.00,A,4131.46063,N,07040.30765,W,0.078,74.83,141220,,,D*4D
2020-12-14T22:02:10.598Z,1607983330.598 [NAL9602](INFO): GPS fix at 20201214T220210: (41.524344, -70.671794)
2020-12-14T22:02:10.618Z,1607983330.618 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T22:02:10.618Z,1607983330.618 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T22:02:14.828Z,1607983334.828 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201214T212730/Courier0021.lzma
2020-12-14T22:02:15.830Z,1607983335.830 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0021.lzma.bak
2020-12-14T22:02:15.831Z,1607983335.831 [DataOverHttps](INFO): SBD MOMSN=66182
2020-12-14T22:02:24.868Z,1607983344.868 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20201214T212730/Express0022.lzma
2020-12-14T22:02:25.870Z,1607983345.870 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0022.lzma.bak
2020-12-14T22:02:25.871Z,1607983345.871 [DataOverHttps](INFO): SBD MOMSN=66185
2020-12-14T22:02:27.184Z,1607983347.184 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T22:02:27.184Z,1607983347.184 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T22:02:27.185Z,1607983347.185 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T22:02:43.326Z,1607983363.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=12948, MT Status=2, MTMSN=0
2020-12-14T22:02:43.327Z,1607983363.327 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-14T22:03:02.718Z,1607983382.718 [NAL9602](INFO): SBD MO Status=2, MOMSN=12948, MT Status=2, MTMSN=0
2020-12-14T22:03:02.718Z,1607983382.718 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-14T22:04:08.161Z,1607983448.161 [NAL9602](INFO): SBD MO Status=2, MOMSN=12948, MT Status=2, MTMSN=0
2020-12-14T22:04:08.161Z,1607983448.161 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2020-12-14T22:04:40.076Z,1607983480.076 [NAL9602](INFO): SBD MO Status=1, MOMSN=12948, MT Status=0, MTMSN=0
2020-12-14T22:04:40.077Z,1607983480.077 [NAL9602](INFO): No messages in MT queue
2020-12-14T22:05:10.779Z,1607983510.779 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T22:06:03.308Z,1607983563.308 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-12-14T22:07:27.758Z,1607983647.758 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T22:07:27.758Z,1607983647.758 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:07:27.758Z,1607983647.758 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:07:27.758Z,1607983647.758 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T22:07:28.146Z,1607983648.146 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T22:07:28.146Z,1607983648.146 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T22:07:28.565Z,1607983648.565 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 55.322074 min 
2020-12-14T22:07:28.565Z,1607983648.565 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T22:07:28.565Z,1607983648.565 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:07:28.565Z,1607983648.565 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:07:28.565Z,1607983648.565 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:07:28.566Z,1607983648.566 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #11
2020-12-14T22:07:28.566Z,1607983648.566 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=11
2020-12-14T22:07:28.566Z,1607983648.566 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:07:28.566Z,1607983648.566 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T22:07:30.577Z,1607983650.577 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220730.00,A,4131.46644,N,07040.28468,W,0.019,100.73,141220,,,D*74
2020-12-14T22:07:30.579Z,1607983650.579 [NAL9602](INFO): GPS fix at 20201214T220730: (41.524441, -70.671411)
2020-12-14T22:07:30.612Z,1607983650.612 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T22:07:30.612Z,1607983650.612 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T22:07:34.164Z,1607983654.164 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20201214T212730/Courier0024.lzma
2020-12-14T22:07:35.167Z,1607983655.167 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0024.lzma.bak
2020-12-14T22:07:35.167Z,1607983655.167 [DataOverHttps](INFO): SBD MOMSN=66189
2020-12-14T22:07:44.217Z,1607983664.217 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20201214T212730/Express0025.lzma
2020-12-14T22:07:45.218Z,1607983665.218 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0025.lzma.bak
2020-12-14T22:07:45.219Z,1607983665.219 [DataOverHttps](INFO): SBD MOMSN=66192
2020-12-14T22:07:47.197Z,1607983667.197 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T22:07:47.197Z,1607983667.197 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T22:07:47.197Z,1607983667.197 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T22:08:00.916Z,1607983680.916 [NAL9602](INFO): SBD MO Status=1, MOMSN=12949, MT Status=0, MTMSN=0
2020-12-14T22:08:00.917Z,1607983680.917 [NAL9602](INFO): No messages in MT queue
2020-12-14T22:08:31.615Z,1607983711.615 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T22:11:08.828Z,1607983868.828 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2020-12-14T22:11:18.522Z,1607983878.522 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2020-12-14T22:11:48.832Z,1607983908.832 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2020-12-14T22:12:47.845Z,1607983967.845 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T22:12:47.845Z,1607983967.845 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:12:47.846Z,1607983967.846 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:12:47.846Z,1607983967.846 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T22:12:48.226Z,1607983968.226 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T22:12:48.226Z,1607983968.226 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T22:12:48.648Z,1607983968.648 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 60.656738 min 
2020-12-14T22:12:48.648Z,1607983968.648 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #12
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=12
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:12:48.649Z,1607983968.649 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T22:12:50.653Z,1607983970.653 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221251.00,A,4131.47456,N,07040.28371,W,0.214,189.54,141220,,,D*73
2020-12-14T22:12:50.655Z,1607983970.655 [NAL9602](INFO): GPS fix at 20201214T221251: (41.524576, -70.671395)
2020-12-14T22:12:50.675Z,1607983970.675 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T22:12:50.675Z,1607983970.675 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T22:12:54.524Z,1607983974.524 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20201214T212730/Courier0027.lzma
2020-12-14T22:12:55.527Z,1607983975.527 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0027.lzma.bak
2020-12-14T22:12:55.527Z,1607983975.527 [DataOverHttps](INFO): SBD MOMSN=66196
2020-12-14T22:13:04.565Z,1607983984.565 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20201214T212730/Express0028.lzma
2020-12-14T22:13:05.566Z,1607983985.566 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0028.lzma.bak
2020-12-14T22:13:05.567Z,1607983985.567 [DataOverHttps](INFO): SBD MOMSN=66199
2020-12-14T22:13:07.219Z,1607983987.219 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T22:13:07.219Z,1607983987.219 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T22:13:07.219Z,1607983987.219 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T22:16:31.653Z,1607984191.653 [RDI_PathfinderUp](ERROR): only read 0 of 4 data items
2020-12-14T22:16:49.015Z,1607984209.015 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-12-14T22:17:53.252Z,1607984273.252 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-14T22:18:07.826Z,1607984287.826 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T22:18:07.826Z,1607984287.826 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:18:07.826Z,1607984287.826 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:18:07.827Z,1607984287.827 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T22:18:08.213Z,1607984288.213 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T22:18:08.213Z,1607984288.213 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T22:18:08.628Z,1607984288.628 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 65.989864 min 
2020-12-14T22:18:08.628Z,1607984288.628 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T22:18:08.628Z,1607984288.628 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:18:08.628Z,1607984288.628 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:18:08.628Z,1607984288.628 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:18:08.629Z,1607984288.629 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #13
2020-12-14T22:18:08.629Z,1607984288.629 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=13
2020-12-14T22:18:08.629Z,1607984288.629 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:18:08.629Z,1607984288.629 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T22:18:10.633Z,1607984290.633 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221811.00,A,4131.46168,N,07040.29461,W,0.194,189.44,141220,,,A*7C
2020-12-14T22:18:10.635Z,1607984290.635 [NAL9602](INFO): GPS fix at 20201214T221811: (41.524361, -70.671577)
2020-12-14T22:18:10.656Z,1607984290.656 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T22:18:10.656Z,1607984290.656 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T22:18:14.876Z,1607984294.876 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0030.lzma
2020-12-14T22:18:15.878Z,1607984295.878 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0030.lzma.bak
2020-12-14T22:18:15.878Z,1607984295.878 [DataOverHttps](INFO): SBD MOMSN=66202
2020-12-14T22:18:24.917Z,1607984304.917 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20201214T212730/Express0031.lzma
2020-12-14T22:18:25.918Z,1607984305.918 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0031.lzma.bak
2020-12-14T22:18:25.919Z,1607984305.919 [DataOverHttps](INFO): SBD MOMSN=66206
2020-12-14T22:18:27.656Z,1607984307.656 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T22:18:27.656Z,1607984307.656 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T22:18:27.656Z,1607984307.656 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T22:18:43.357Z,1607984323.357 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T22:19:11.638Z,1607984351.638 [RDI_PathfinderUp](ERROR): only read 0 of 1 data item for BIT error
2020-12-14T22:23:28.183Z,1607984608.183 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-14T22:23:28.183Z,1607984608.183 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:23:28.183Z,1607984608.183 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:23:28.183Z,1607984608.183 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2020-12-14T22:23:28.606Z,1607984608.606 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2020-12-14T22:23:28.607Z,1607984608.607 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2020-12-14T22:23:29.021Z,1607984609.021 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for 71.329753 min 
2020-12-14T22:23:29.021Z,1607984609.021 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #14
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=14
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:23:29.022Z,1607984609.022 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2020-12-14T22:23:31.002Z,1607984611.002 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222331.00,A,4131.45342,N,07040.31726,W,0.855,205.99,141220,,,A*75
2020-12-14T22:23:31.004Z,1607984611.004 [NAL9602](INFO): GPS fix at 20201214T222331: (41.524224, -70.671954)
2020-12-14T22:23:31.020Z,1607984611.020 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2020-12-14T22:23:31.020Z,1607984611.020 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2020-12-14T22:23:35.188Z,1607984615.188 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20201214T212730/Courier0033.lzma
2020-12-14T22:23:36.218Z,1607984616.218 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Courier0033.lzma.bak
2020-12-14T22:23:36.218Z,1607984616.218 [DataOverHttps](INFO): SBD MOMSN=66210
2020-12-14T22:23:45.245Z,1607984625.245 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20201214T212730/Express0034.lzma
2020-12-14T22:23:46.251Z,1607984626.251 [DataOverHttps](INFO): Moved sent file to Logs/20201214T212730/Express0034.lzma.bak
2020-12-14T22:23:46.251Z,1607984626.251 [DataOverHttps](INFO): SBD MOMSN=66213
2020-12-14T22:23:49.172Z,1607984629.172 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2020-12-14T22:23:49.172Z,1607984629.172 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2020-12-14T22:23:49.172Z,1607984629.172 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-14T22:24:01.566Z,1607984641.566 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2020-12-14T22:24:01.645Z,1607984641.645 [NAL9602](FAULT): received: 
+CSQ:0

OK949, 0, 0, 0, 0

OK
2020-12-14T22:24:01.645Z,1607984641.645 [NAL9602] Data Fault, FailCount= 1
2020-12-14T22:24:01.645Z,1607984641.645 [NAL9602](ERROR): Data Fault
2020-12-14T22:24:01.722Z,1607984641.722 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-14T22:24:01.980Z,1607984641.980 [NAL9602](INFO): Powering down
2020-12-14T22:24:02.832Z,1607984642.832 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-14T22:24:02.832Z,1607984642.832 [NAL9602] No Fault, FailCount= 1
2020-12-14T22:24:32.275Z,1607984672.275 [NAL9602](INFO): Powering up NAL9602
2020-12-14T22:24:43.183Z,1607984683.183 [NAL9602](INFO): NAL9602 initialized
2020-12-14T22:25:14.326Z,1607984714.326 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-14T22:25:33.961Z,1607984733.961 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251427
2020-12-14T22:26:10.106Z,1607984770.106 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-14T22:26:45.245Z,1607984805.245 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-12-14T22:27:14.246Z,1607984834.246 [CommandLine](IMPORTANT): got command quit
2020-12-14T22:27:15.293Z,1607984835.293 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:15.293Z,1607984835.293 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.308Z,1607984835.308 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-12-14T22:27:15.309Z,1607984835.309 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.309Z,1607984835.309 [CommandLine](INFO): Join timeout helper Thread ID is 4285
2020-12-14T22:27:15.310Z,1607984835.310 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-12-14T22:27:15.310Z,1607984835.310 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.311Z,1607984835.311 [NavChartDb](INFO): Join timeout helper Thread ID is 4286
2020-12-14T22:27:15.561Z,1607984835.561 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:15.561Z,1607984835.561 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.573Z,1607984835.573 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2020-12-14T22:27:15.573Z,1607984835.573 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.573Z,1607984835.573 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 4287
2020-12-14T22:27:15.574Z,1607984835.574 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:15.574Z,1607984835.574 [WetLabsSeaOWL_UV_A](INFO): Powering down
2020-12-14T22:27:15.575Z,1607984835.575 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.575Z,1607984835.575 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-12-14T22:27:15.575Z,1607984835.575 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.575Z,1607984835.575 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4288
2020-12-14T22:27:15.593Z,1607984835.593 [CTD_Seabird](INFO): Powering down
2020-12-14T22:27:15.605Z,1607984835.605 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:15.605Z,1607984835.605 [CTD_Seabird](INFO): Powering down
2020-12-14T22:27:15.617Z,1607984835.617 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.621Z,1607984835.621 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-12-14T22:27:15.621Z,1607984835.621 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:15.621Z,1607984835.621 [Radio_Surface](INFO): Join timeout helper Thread ID is 4289
2020-12-14T22:27:16.009Z,1607984836.009 [Radio_Surface](INFO): Powering down
2020-12-14T22:27:16.010Z,1607984836.010 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:16.010Z,1607984836.010 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:16.014Z,1607984836.014 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-12-14T22:27:16.014Z,1607984836.014 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:16.014Z,1607984836.014 [Onboard](INFO): Join timeout helper Thread ID is 4290
2020-12-14T22:27:19.253Z,1607984839.253 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:19.253Z,1607984839.253 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.262Z,1607984839.262 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-12-14T22:27:19.262Z,1607984839.262 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.263Z,1607984839.263 [DataOverHttps](INFO): Join timeout helper Thread ID is 4291
2020-12-14T22:27:19.389Z,1607984839.389 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:19.389Z,1607984839.389 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.401Z,1607984839.401 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-12-14T22:27:19.401Z,1607984839.401 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.402Z,1607984839.402 [logger](INFO): Join timeout helper Thread ID is 4292
2020-12-14T22:27:19.429Z,1607984839.429 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:19.430Z,1607984839.430 [logger ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.443Z,1607984839.443 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-12-14T22:27:19.443Z,1607984839.443 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.443Z,1607984839.443 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-12-14T22:27:19.444Z,1607984839.444 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:19.445Z,1607984839.445 [controlThread](INFO): Join timeout helper Thread ID is 4293
2020-12-14T22:27:19.509Z,1607984839.509 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-12-14T22:27:19.509Z,1607984839.509 [controlThread](DEBUG): Uninitializing ControlThread
2020-12-14T22:27:19.510Z,1607984839.510 [AHRS_M2](INFO): Powering down
2020-12-14T22:27:19.581Z,1607984839.581 [DDM](INFO): Powering down
2020-12-14T22:27:19.653Z,1607984839.653 [DUSBL_Hydroid](INFO): Powering down
2020-12-14T22:27:19.725Z,1607984839.725 [Micromodem](INFO): Powering down
2020-12-14T22:27:19.821Z,1607984839.821 [NAL9602](INFO): Powering down
2020-12-14T22:27:19.893Z,1607984839.893 [RDI_Pathfinder](INFO): Powering down
2020-12-14T22:27:19.965Z,1607984839.965 [RDI_PathfinderUp](INFO): Powering down
2020-12-14T22:27:19.966Z,1607984839.966 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-12-14T22:27:19.967Z,1607984839.967 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-12-14T22:27:19.968Z,1607984839.968 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-12-14T22:27:19.968Z,1607984839.968 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2020-12-14T22:27:19.968Z,1607984839.968 [DefaultWithUndock] Stopped
2020-12-14T22:27:19.968Z,1607984839.968 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault] Stopped
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:B.GoToSurface] Stopped
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2020-12-14T22:27:19.969Z,1607984839.969 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-14T22:27:19.976Z,1607984839.976 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-12-14T22:27:19.977Z,1607984839.977 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-12-14T22:27:19.977Z,1607984839.977 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-12-14T22:27:19.977Z,1607984839.977 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-12-14T22:27:19.978Z,1607984839.978 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-12-14T22:27:19.978Z,1607984839.978 [BuoyancyServo](INFO): Powering down
2020-12-14T22:27:19.993Z,1607984839.993 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-12-14T22:27:19.993Z,1607984839.993 [ElevatorServo](INFO): Powering down
2020-12-14T22:27:19.994Z,1607984839.994 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-12-14T22:27:19.994Z,1607984839.994 [MassServo](INFO): Powering down
2020-12-14T22:27:19.994Z,1607984839.994 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-14T22:27:19.995Z,1607984839.995 [RudderServo](INFO): Powering down
2020-12-14T22:27:19.995Z,1607984839.995 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-12-14T22:27:19.995Z,1607984839.995 [ThrusterServo](INFO): Powering down
2020-12-14T22:27:19.996Z,1607984839.996 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-12-14T22:27:19.997Z,1607984839.997 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-12-14T22:27:19.997Z,1607984839.997 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-12-14T22:27:19.997Z,1607984839.997 [CBIT](DEBUG): Powering off loads.
2020-12-14T22:27:20.008Z,1607984840.008 [CBIT](DEBUG): Disabling WDT.
2020-12-14T22:27:20.020Z,1607984840.020 [CBIT](DEBUG): Opening all GF detection circuits.
2020-12-14T22:27:20.021Z,1607984840.021 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.029Z,1607984840.029 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.032Z,1607984840.032 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.043Z,1607984840.043 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.127Z,1607984840.127 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.131Z,1607984840.131 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.191Z,1607984840.191 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-12-14T22:27:20.261Z,1607984840.261 [logger ThreadHandler](INFO): Thread cancelled.