2019-12-17T18:50:39.167Z,1576608639.167 [CommandLine](IMPORTANT): got command restart logs 2019-12-17T18:51:35.391Z,1576608695.391 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-12-17T18:51:41.415Z,1576608701.415 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.263149 2019-12-17T18:52:45.671Z,1576608765.671 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2019-12-17T18:53:20.134Z,1576608800.134 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003340 2019-12-17T18:54:45.505Z,1576608885.505 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T18:54:45.505Z,1576608885.505 [Default:CheckIn:C.Wait] Stopped 2019-12-17T18:54:45.505Z,1576608885.505 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T18:54:45.506Z,1576608885.506 [Default:CheckIn:D] Running Loop=1 2019-12-17T18:54:45.923Z,1576608885.923 [Default:CheckIn:D] Stopped 2019-12-17T18:54:45.923Z,1576608885.923 [Default:CheckIn:E] Running Loop=1 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.853168 min 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn:E] Stopped 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn] Stopped 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T18:54:46.317Z,1576608886.317 [Default:CheckIn](INFO): Running loop #4 2019-12-17T18:54:46.318Z,1576608886.318 [Default:CheckIn] Running Loop=4 2019-12-17T18:54:46.318Z,1576608886.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T18:54:46.318Z,1576608886.318 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T18:54:48.310Z,1576608888.310 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185452.00,A,3648.45024,N,12147.28347,W,8.747,53.92,171219,,,D*4F 2019-12-17T18:54:48.312Z,1576608888.312 [NAL9602](INFO): GPS fix at 20191217T185452: (36.807504, -121.788058) 2019-12-17T18:54:48.336Z,1576608888.336 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T18:54:48.336Z,1576608888.336 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T18:54:56.102Z,1576608896.102 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191217T185039/Courier0000.lzma 2019-12-17T18:54:58.108Z,1576608898.108 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0000.lzma.bak 2019-12-17T18:54:58.108Z,1576608898.108 [DataOverHttps](INFO): SBD MOMSN=12178036 2019-12-17T18:55:03.253Z,1576608903.253 [NAL9602](INFO): SBD MO Status=0, MOMSN=4007, MT Status=0, MTMSN=0 2019-12-17T18:55:03.253Z,1576608903.253 [NAL9602](INFO): No messages in MT queue 2019-12-17T18:55:14.958Z,1576608914.958 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20191217T015554/Express0115.lzma 2019-12-17T18:55:16.964Z,1576608916.964 [DataOverHttps](INFO): Moved sent file to Logs/20191217T015554/Express0115.lzma.bak 2019-12-17T18:55:16.964Z,1576608916.964 [DataOverHttps](INFO): SBD MOMSN=12178040 2019-12-17T18:55:33.886Z,1576608933.886 [DataOverHttps](INFO): Sending 337 bytes from file Logs/20191217T185039/Express0001.lzma 2019-12-17T18:55:33.959Z,1576608933.959 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T18:55:35.892Z,1576608935.892 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0001.lzma.bak 2019-12-17T18:55:35.892Z,1576608935.892 [DataOverHttps](INFO): SBD MOMSN=12178047 2019-12-17T18:55:52.650Z,1576608952.650 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20191217T185039/Express0004.lzma 2019-12-17T18:55:54.656Z,1576608954.656 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0004.lzma.bak 2019-12-17T18:55:54.656Z,1576608954.656 [DataOverHttps](INFO): SBD MOMSN=12178054 2019-12-17T18:55:55.797Z,1576608955.797 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T18:55:55.797Z,1576608955.797 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T18:55:55.797Z,1576608955.797 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T18:59:33.971Z,1576609173.971 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T18:59:40.838Z,1576609180.838 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T18:59:56.186Z,1576609196.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:00:11.548Z,1576609211.548 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:00:26.491Z,1576609226.491 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:00:41.438Z,1576609241.438 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:00:56.403Z,1576609256.403 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:00:56.403Z,1576609256.403 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:00:56.403Z,1576609256.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:00:56.403Z,1576609256.403 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:00:56.897Z,1576609256.897 [Default:CheckIn:D] Stopped 2019-12-17T19:00:56.897Z,1576609256.897 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:00:57.197Z,1576609257.197 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:00:57.212Z,1576609257.212 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.036076 min 2019-12-17T19:00:57.212Z,1576609257.212 [Default:CheckIn:E] Stopped 2019-12-17T19:00:57.212Z,1576609257.212 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn] Stopped 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn](INFO): Running loop #5 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn] Running Loop=5 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:00:57.213Z,1576609257.213 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:00:59.206Z,1576609259.206 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190103.00,A,3648.13925,N,12147.19943,W,1.886,234.11,171219,,,D*7E 2019-12-17T19:00:59.208Z,1576609259.208 [NAL9602](INFO): GPS fix at 20191217T190103: (36.802321, -121.786657) 2019-12-17T19:00:59.232Z,1576609259.232 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:00:59.233Z,1576609259.233 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:01:06.714Z,1576609266.714 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0006.lzma 2019-12-17T19:01:08.720Z,1576609268.720 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0006.lzma.bak 2019-12-17T19:01:08.720Z,1576609268.720 [DataOverHttps](INFO): SBD MOMSN=12178094 2019-12-17T19:01:12.543Z,1576609272.543 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:01:13.337Z,1576609273.337 [NAL9602](INFO): SBD MO Status=0, MOMSN=4008, MT Status=0, MTMSN=0 2019-12-17T19:01:13.337Z,1576609273.337 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:01:25.774Z,1576609285.774 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20191217T185039/Express0007.lzma 2019-12-17T19:01:27.780Z,1576609287.780 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0007.lzma.bak 2019-12-17T19:01:27.780Z,1576609287.780 [DataOverHttps](INFO): SBD MOMSN=12178099 2019-12-17T19:01:27.893Z,1576609287.893 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:01:29.132Z,1576609289.132 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:01:29.132Z,1576609289.132 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:01:29.132Z,1576609289.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:01:43.247Z,1576609303.247 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:01:44.043Z,1576609304.043 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:01:58.204Z,1576609318.204 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T19:02:04.674Z,1576609324.674 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-12-17T19:02:04.677Z,1576609324.677 [BPC1](INFO): Received data from all battery sticks. 2019-12-17T19:06:29.694Z,1576609589.694 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:06:29.694Z,1576609589.694 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:06:29.695Z,1576609589.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:06:29.695Z,1576609589.695 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:06:30.104Z,1576609590.104 [Default:CheckIn:D] Stopped 2019-12-17T19:06:30.104Z,1576609590.104 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:06:30.504Z,1576609590.504 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.589520 min 2019-12-17T19:06:30.504Z,1576609590.504 [Default:CheckIn:E] Stopped 2019-12-17T19:06:30.504Z,1576609590.504 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:06:30.504Z,1576609590.504 [Default:CheckIn] Stopped 2019-12-17T19:06:30.504Z,1576609590.504 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:06:30.505Z,1576609590.505 [Default:CheckIn](INFO): Running loop #6 2019-12-17T19:06:30.505Z,1576609590.505 [Default:CheckIn] Running Loop=6 2019-12-17T19:06:30.505Z,1576609590.505 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:06:30.505Z,1576609590.505 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:06:32.501Z,1576609592.501 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190636.00,A,3648.13615,N,12147.20637,W,0.058,246.22,171219,,,D*7A 2019-12-17T19:06:32.503Z,1576609592.503 [NAL9602](INFO): GPS fix at 20191217T190636: (36.802269, -121.786773) 2019-12-17T19:06:32.528Z,1576609592.528 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:06:32.528Z,1576609592.528 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:06:40.215Z,1576609600.215 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20191217T185039/Courier0009.lzma 2019-12-17T19:06:42.220Z,1576609602.220 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0009.lzma.bak 2019-12-17T19:06:42.220Z,1576609602.220 [DataOverHttps](INFO): SBD MOMSN=12178118 2019-12-17T19:06:59.058Z,1576609619.058 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20191217T185039/Express0010.lzma 2019-12-17T19:06:59.977Z,1576609619.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=4009, MT Status=2, MTMSN=0 2019-12-17T19:06:59.977Z,1576609619.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T19:07:01.064Z,1576609621.064 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0010.lzma.bak 2019-12-17T19:07:01.064Z,1576609621.064 [DataOverHttps](INFO): SBD MOMSN=12178122 2019-12-17T19:07:02.433Z,1576609622.433 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:07:02.434Z,1576609622.434 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:07:02.434Z,1576609622.434 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:07:16.133Z,1576609636.133 [NAL9602](INFO): SBD MO Status=2, MOMSN=4009, MT Status=2, MTMSN=0 2019-12-17T19:07:16.133Z,1576609636.133 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T19:07:27.453Z,1576609647.453 [NAL9602](INFO): SBD MO Status=0, MOMSN=4009, MT Status=0, MTMSN=0 2019-12-17T19:07:27.453Z,1576609647.453 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:07:58.150Z,1576609678.150 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:12:02.994Z,1576609922.994 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:12:02.994Z,1576609922.994 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:12:02.994Z,1576609922.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:12:02.994Z,1576609922.994 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:12:03.404Z,1576609923.404 [Default:CheckIn:D] Stopped 2019-12-17T19:12:03.404Z,1576609923.404 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:12:03.807Z,1576609923.807 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 37.144519 min 2019-12-17T19:12:03.807Z,1576609923.807 [Default:CheckIn:E] Stopped 2019-12-17T19:12:03.807Z,1576609923.807 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:12:03.807Z,1576609923.807 [Default:CheckIn] Stopped 2019-12-17T19:12:03.807Z,1576609923.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:12:03.808Z,1576609923.808 [Default:CheckIn](INFO): Running loop #7 2019-12-17T19:12:03.808Z,1576609923.808 [Default:CheckIn] Running Loop=7 2019-12-17T19:12:03.808Z,1576609923.808 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:12:03.808Z,1576609923.808 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:12:05.801Z,1576609925.801 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191209.00,A,3648.13742,N,12147.20432,W,0.097,246.22,171219,,,A*71 2019-12-17T19:12:05.803Z,1576609925.803 [NAL9602](INFO): GPS fix at 20191217T191209: (36.802290, -121.786739) 2019-12-17T19:12:05.853Z,1576609925.853 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:12:05.854Z,1576609925.854 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:12:13.482Z,1576609933.482 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0012.lzma 2019-12-17T19:12:15.488Z,1576609935.488 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0012.lzma.bak 2019-12-17T19:12:15.488Z,1576609935.488 [DataOverHttps](INFO): SBD MOMSN=12178134 2019-12-17T19:12:26.401Z,1576609946.401 [NAL9602](INFO): SBD MO Status=0, MOMSN=4010, MT Status=0, MTMSN=0 2019-12-17T19:12:26.401Z,1576609946.401 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:12:32.414Z,1576609952.414 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191217T185039/Express0013.lzma 2019-12-17T19:12:34.420Z,1576609954.420 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0013.lzma.bak 2019-12-17T19:12:34.420Z,1576609954.420 [DataOverHttps](INFO): SBD MOMSN=12178137 2019-12-17T19:12:35.728Z,1576609955.728 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:12:35.728Z,1576609955.728 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:12:35.728Z,1576609955.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:12:57.101Z,1576609977.101 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:17:36.322Z,1576610256.322 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:17:36.322Z,1576610256.322 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:17:36.322Z,1576610256.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:17:36.323Z,1576610256.323 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:17:36.711Z,1576610256.711 [Default:CheckIn:D] Stopped 2019-12-17T19:17:36.711Z,1576610256.711 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.699642 min 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn:E] Stopped 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn] Stopped 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn](INFO): Running loop #8 2019-12-17T19:17:37.121Z,1576610257.121 [Default:CheckIn] Running Loop=8 2019-12-17T19:17:37.122Z,1576610257.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:17:37.122Z,1576610257.122 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:17:39.109Z,1576610259.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191743.00,A,3648.14165,N,12147.20841,W,0.019,343.94,171219,,,A*79 2019-12-17T19:17:39.111Z,1576610259.111 [NAL9602](INFO): GPS fix at 20191217T191743: (36.802361, -121.786807) 2019-12-17T19:17:39.151Z,1576610259.151 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:17:39.151Z,1576610259.151 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:17:46.694Z,1576610266.694 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191217T185039/Courier0015.lzma 2019-12-17T19:17:48.700Z,1576610268.700 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0015.lzma.bak 2019-12-17T19:17:48.700Z,1576610268.700 [DataOverHttps](INFO): SBD MOMSN=12178150 2019-12-17T19:17:48.801Z,1576610268.801 [NAL9602](INFO): SBD MO Status=0, MOMSN=4011, MT Status=0, MTMSN=0 2019-12-17T19:17:48.801Z,1576610268.801 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:18:05.430Z,1576610285.430 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191217T185039/Express0016.lzma 2019-12-17T19:18:07.436Z,1576610287.436 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0016.lzma.bak 2019-12-17T19:18:07.436Z,1576610287.436 [DataOverHttps](INFO): SBD MOMSN=12178155 2019-12-17T19:18:08.632Z,1576610288.632 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:18:08.632Z,1576610288.632 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:18:08.633Z,1576610288.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:18:19.501Z,1576610299.501 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:23:09.198Z,1576610589.198 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:23:09.199Z,1576610589.199 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:23:09.199Z,1576610589.199 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:23:09.199Z,1576610589.199 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:23:09.610Z,1576610589.610 [Default:CheckIn:D] Stopped 2019-12-17T19:23:09.610Z,1576610589.610 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.247957 min 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn:E] Stopped 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn] Stopped 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn](INFO): Running loop #9 2019-12-17T19:23:10.013Z,1576610590.013 [Default:CheckIn] Running Loop=9 2019-12-17T19:23:10.014Z,1576610590.014 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:23:10.014Z,1576610590.014 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:23:11.999Z,1576610591.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192316.00,A,3648.15636,N,12147.25102,W,3.246,21.50,171219,,,A*41 2019-12-17T19:23:12.001Z,1576610592.001 [NAL9602](INFO): GPS fix at 20191217T192316: (36.802606, -121.787517) 2019-12-17T19:23:12.054Z,1576610592.054 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:23:12.054Z,1576610592.054 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:23:19.734Z,1576610599.734 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191217T185039/Courier0018.lzma 2019-12-17T19:23:21.740Z,1576610601.740 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0018.lzma.bak 2019-12-17T19:23:21.740Z,1576610601.740 [DataOverHttps](INFO): SBD MOMSN=12178164 2019-12-17T19:23:38.762Z,1576610618.762 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191217T185039/Express0019.lzma 2019-12-17T19:23:40.768Z,1576610620.768 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0019.lzma.bak 2019-12-17T19:23:40.768Z,1576610620.768 [DataOverHttps](INFO): SBD MOMSN=12178171 2019-12-17T19:23:41.939Z,1576610621.939 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:23:41.939Z,1576610621.939 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:23:41.939Z,1576610621.939 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:23:42.305Z,1576610622.305 [NAL9602](INFO): SBD MO Status=0, MOMSN=4012, MT Status=0, MTMSN=0 2019-12-17T19:23:42.305Z,1576610622.305 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:24:13.007Z,1576610653.007 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:28:42.501Z,1576610922.501 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:28:42.501Z,1576610922.501 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:28:42.501Z,1576610922.501 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:28:42.501Z,1576610922.501 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:28:42.916Z,1576610922.916 [Default:CheckIn:D] Stopped 2019-12-17T19:28:42.916Z,1576610922.916 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:28:43.304Z,1576610923.304 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.803056 min 2019-12-17T19:28:43.304Z,1576610923.304 [Default:CheckIn:E] Stopped 2019-12-17T19:28:43.304Z,1576610923.304 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:28:43.304Z,1576610923.304 [Default:CheckIn] Stopped 2019-12-17T19:28:43.305Z,1576610923.305 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:28:43.305Z,1576610923.305 [Default:CheckIn](INFO): Running loop #10 2019-12-17T19:28:43.305Z,1576610923.305 [Default:CheckIn] Running Loop=10 2019-12-17T19:28:43.305Z,1576610923.305 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:28:43.305Z,1576610923.305 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:28:45.299Z,1576610925.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192849.00,A,3648.16807,N,12147.28113,W,0.350,216.04,171219,,,A*70 2019-12-17T19:28:45.301Z,1576610925.301 [NAL9602](INFO): GPS fix at 20191217T192849: (36.802801, -121.788019) 2019-12-17T19:28:45.339Z,1576610925.339 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:28:45.339Z,1576610925.339 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:28:53.278Z,1576610933.278 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0021.lzma 2019-12-17T19:28:55.284Z,1576610935.284 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0021.lzma.bak 2019-12-17T19:28:55.284Z,1576610935.284 [DataOverHttps](INFO): SBD MOMSN=12178202 2019-12-17T19:29:12.110Z,1576610952.110 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20191217T185039/Express0022.lzma 2019-12-17T19:29:14.116Z,1576610954.116 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0022.lzma.bak 2019-12-17T19:29:14.116Z,1576610954.116 [DataOverHttps](INFO): SBD MOMSN=12178208 2019-12-17T19:29:15.311Z,1576610955.311 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:29:15.311Z,1576610955.311 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:29:15.311Z,1576610955.311 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:33:47.557Z,1576611227.557 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-12-17T19:34:15.866Z,1576611255.866 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:34:15.867Z,1576611255.867 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:34:15.867Z,1576611255.867 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:34:15.867Z,1576611255.867 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:34:16.276Z,1576611256.276 [Default:CheckIn:D] Stopped 2019-12-17T19:34:16.276Z,1576611256.276 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 59.359062 min 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn:E] Stopped 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn] Stopped 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn](INFO): Running loop #11 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn] Running Loop=11 2019-12-17T19:34:16.669Z,1576611256.669 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:34:16.670Z,1576611256.670 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:34:18.688Z,1576611258.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193422.00,A,3648.16636,N,12147.28383,W,0.389,216.04,171219,,,A*73 2019-12-17T19:34:18.691Z,1576611258.691 [NAL9602](INFO): GPS fix at 20191217T193422: (36.802773, -121.788064) 2019-12-17T19:34:18.730Z,1576611258.730 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:34:18.730Z,1576611258.730 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:34:26.078Z,1576611266.078 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191217T185039/Courier0024.lzma 2019-12-17T19:34:28.084Z,1576611268.084 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0024.lzma.bak 2019-12-17T19:34:28.084Z,1576611268.084 [DataOverHttps](INFO): SBD MOMSN=12178214 2019-12-17T19:34:46.886Z,1576611286.886 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20191217T185039/Express0025.lzma 2019-12-17T19:34:48.892Z,1576611288.892 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0025.lzma.bak 2019-12-17T19:34:48.892Z,1576611288.892 [DataOverHttps](INFO): SBD MOMSN=12178218 2019-12-17T19:34:50.226Z,1576611290.226 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:34:50.226Z,1576611290.226 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:34:50.226Z,1576611290.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:34:50.593Z,1576611290.593 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:39:50.828Z,1576611590.828 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:39:50.828Z,1576611590.828 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:39:50.828Z,1576611590.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:39:50.829Z,1576611590.829 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:39:51.235Z,1576611591.235 [Default:CheckIn:D] Stopped 2019-12-17T19:39:51.236Z,1576611591.236 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.941715 min 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn:E] Stopped 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn] Stopped 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn](INFO): Running loop #12 2019-12-17T19:39:51.650Z,1576611591.650 [Default:CheckIn] Running Loop=12 2019-12-17T19:39:51.651Z,1576611591.651 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:39:51.651Z,1576611591.651 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:39:53.633Z,1576611593.633 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193957.00,A,3648.16402,N,12147.28166,W,0.933,154.28,171219,,,A*70 2019-12-17T19:39:53.635Z,1576611593.635 [NAL9602](INFO): GPS fix at 20191217T193957: (36.802734, -121.788028) 2019-12-17T19:39:53.677Z,1576611593.677 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:39:53.677Z,1576611593.677 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:40:01.226Z,1576611601.226 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0027.lzma 2019-12-17T19:40:03.232Z,1576611603.232 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0027.lzma.bak 2019-12-17T19:40:03.232Z,1576611603.232 [DataOverHttps](INFO): SBD MOMSN=12178244 2019-12-17T19:40:20.322Z,1576611620.322 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191217T185039/Express0028.lzma 2019-12-17T19:40:22.329Z,1576611622.329 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0028.lzma.bak 2019-12-17T19:40:22.329Z,1576611622.329 [DataOverHttps](INFO): SBD MOMSN=12178249 2019-12-17T19:40:23.561Z,1576611623.561 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:40:23.561Z,1576611623.561 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:40:23.561Z,1576611623.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:40:24.331Z,1576611624.331 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-12-17T19:40:24.406Z,1576611624.406 [NAL9602](FAULT): received: +CSQ:0 OK12, 0, 0, 0, 0 OK 2019-12-17T19:40:24.406Z,1576611624.406 [NAL9602] Data Fault, FailCount= 1 2019-12-17T19:40:24.406Z,1576611624.406 [NAL9602](ERROR): Data Fault 2019-12-17T19:40:24.467Z,1576611624.467 [CBIT](ERROR): Data Fault in component: NAL9602 2019-12-17T19:40:24.739Z,1576611624.739 [NAL9602](INFO): Powering down 2019-12-17T19:40:25.602Z,1576611625.602 [CBIT](INFO): Clearing failed state for component NAL9602 2019-12-17T19:40:25.606Z,1576611625.606 [NAL9602] No Fault, FailCount= 1 2019-12-17T19:40:55.039Z,1576611655.039 [NAL9602](INFO): Powering up NAL9602 2019-12-17T19:41:05.943Z,1576611665.943 [NAL9602](INFO): NAL9602 initialized 2019-12-17T19:41:37.047Z,1576611697.047 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:45:24.128Z,1576611924.128 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:45:24.128Z,1576611924.128 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:45:24.128Z,1576611924.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:45:24.129Z,1576611924.129 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:45:24.534Z,1576611924.534 [Default:CheckIn:D] Stopped 2019-12-17T19:45:24.534Z,1576611924.534 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:45:24.931Z,1576611924.931 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 70.496688 min 2019-12-17T19:45:24.931Z,1576611924.931 [Default:CheckIn:E] Stopped 2019-12-17T19:45:24.931Z,1576611924.931 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:45:24.931Z,1576611924.931 [Default:CheckIn] Stopped 2019-12-17T19:45:24.931Z,1576611924.931 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:45:24.932Z,1576611924.932 [Default:CheckIn](INFO): Running loop #13 2019-12-17T19:45:24.932Z,1576611924.932 [Default:CheckIn] Running Loop=13 2019-12-17T19:45:24.932Z,1576611924.932 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:45:24.932Z,1576611924.932 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:45:26.923Z,1576611926.923 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194530.00,A,3648.16919,N,12147.28247,W,2.702,0.00,171219,,,A*79 2019-12-17T19:45:26.925Z,1576611926.925 [NAL9602](INFO): GPS fix at 20191217T194530: (36.802820, -121.788041) 2019-12-17T19:45:26.970Z,1576611926.970 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:45:26.970Z,1576611926.970 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:45:29.353Z,1576611929.353 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-12-17T19:45:34.278Z,1576611934.278 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0030.lzma 2019-12-17T19:45:36.284Z,1576611936.284 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0030.lzma.bak 2019-12-17T19:45:36.284Z,1576611936.284 [DataOverHttps](INFO): SBD MOMSN=12178297 2019-12-17T19:45:53.086Z,1576611953.086 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20191217T185039/Express0031.lzma 2019-12-17T19:45:55.092Z,1576611955.092 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0031.lzma.bak 2019-12-17T19:45:55.092Z,1576611955.092 [DataOverHttps](INFO): SBD MOMSN=12178303 2019-12-17T19:45:56.449Z,1576611956.449 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:45:56.449Z,1576611956.449 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:45:56.449Z,1576611956.449 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:46:00.055Z,1576611960.055 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:50:57.013Z,1576612257.013 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:50:57.013Z,1576612257.013 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:50:57.013Z,1576612257.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:50:57.013Z,1576612257.013 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:50:57.424Z,1576612257.424 [Default:CheckIn:D] Stopped 2019-12-17T19:50:57.424Z,1576612257.424 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:50:57.827Z,1576612257.827 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.044849 min 2019-12-17T19:50:57.827Z,1576612257.827 [Default:CheckIn:E] Stopped 2019-12-17T19:50:57.827Z,1576612257.827 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:50:57.827Z,1576612257.827 [Default:CheckIn] Stopped 2019-12-17T19:50:57.828Z,1576612257.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:50:57.828Z,1576612257.828 [Default:CheckIn](INFO): Running loop #14 2019-12-17T19:50:57.828Z,1576612257.828 [Default:CheckIn] Running Loop=14 2019-12-17T19:50:57.828Z,1576612257.828 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:50:57.828Z,1576612257.828 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:50:59.819Z,1576612259.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195102.00,A,3648.16208,N,12147.27639,W,0.292,344.72,171219,,,D*79 2019-12-17T19:50:59.821Z,1576612259.821 [NAL9602](INFO): GPS fix at 20191217T195102: (36.802701, -121.787940) 2019-12-17T19:50:59.866Z,1576612259.866 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:50:59.867Z,1576612259.867 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:51:07.158Z,1576612267.158 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0033.lzma 2019-12-17T19:51:09.164Z,1576612269.164 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0033.lzma.bak 2019-12-17T19:51:09.164Z,1576612269.164 [DataOverHttps](INFO): SBD MOMSN=12178321 2019-12-17T19:51:26.026Z,1576612286.026 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20191217T185039/Express0034.lzma 2019-12-17T19:51:28.032Z,1576612288.032 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0034.lzma.bak 2019-12-17T19:51:28.032Z,1576612288.032 [DataOverHttps](INFO): SBD MOMSN=12178324 2019-12-17T19:51:29.346Z,1576612289.346 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:51:29.346Z,1576612289.346 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:51:29.346Z,1576612289.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:51:40.624Z,1576612300.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=4013, MT Status=0, MTMSN=0 2019-12-17T19:51:40.624Z,1576612300.624 [NAL9602](INFO): No messages in MT queue 2019-12-17T19:52:11.331Z,1576612331.331 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T19:56:29.915Z,1576612589.915 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T19:56:29.915Z,1576612589.915 [Default:CheckIn:C.Wait] Stopped 2019-12-17T19:56:29.915Z,1576612589.915 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T19:56:29.917Z,1576612589.917 [Default:CheckIn:D] Running Loop=1 2019-12-17T19:56:30.324Z,1576612590.324 [Default:CheckIn:D] Stopped 2019-12-17T19:56:30.324Z,1576612590.324 [Default:CheckIn:E] Running Loop=1 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 81.593188 min 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn:E] Stopped 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn] Stopped 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn](INFO): Running loop #15 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn] Running Loop=15 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T19:56:30.733Z,1576612590.733 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T19:56:32.719Z,1576612592.719 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195636.00,A,3648.16276,N,12147.27932,W,0.194,151.91,171219,,,D*7A 2019-12-17T19:56:32.721Z,1576612592.721 [NAL9602](INFO): GPS fix at 20191217T195636: (36.802713, -121.787989) 2019-12-17T19:56:32.771Z,1576612592.771 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T19:56:32.771Z,1576612592.771 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T19:56:40.042Z,1576612600.042 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191217T185039/Courier0036.lzma 2019-12-17T19:56:42.048Z,1576612602.048 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0036.lzma.bak 2019-12-17T19:56:42.048Z,1576612602.048 [DataOverHttps](INFO): SBD MOMSN=12178335 2019-12-17T19:56:58.894Z,1576612618.894 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191217T185039/Express0037.lzma 2019-12-17T19:57:00.900Z,1576612620.900 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0037.lzma.bak 2019-12-17T19:57:00.900Z,1576612620.900 [DataOverHttps](INFO): SBD MOMSN=12178338 2019-12-17T19:57:02.122Z,1576612622.122 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T19:57:02.122Z,1576612622.122 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T19:57:02.123Z,1576612622.123 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T19:58:30.569Z,1576612710.569 [NAL9602](INFO): SBD MO Status=2, MOMSN=4014, MT Status=2, MTMSN=0 2019-12-17T19:58:30.569Z,1576612710.569 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T19:58:51.185Z,1576612731.185 [NAL9602](INFO): SBD MO Status=2, MOMSN=4014, MT Status=2, MTMSN=0 2019-12-17T19:58:51.187Z,1576612731.187 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T20:00:47.541Z,1576612847.541 [NAL9602](INFO): SBD MO Status=2, MOMSN=4014, MT Status=2, MTMSN=0 2019-12-17T20:00:47.541Z,1576612847.541 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T20:01:25.514Z,1576612885.514 [NAL9602](INFO): SBD MO Status=0, MOMSN=4014, MT Status=0, MTMSN=0 2019-12-17T20:01:25.514Z,1576612885.514 [NAL9602](INFO): No messages in MT queue 2019-12-17T20:01:56.219Z,1576612916.219 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T20:02:02.713Z,1576612922.713 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T20:02:02.713Z,1576612922.713 [Default:CheckIn:C.Wait] Stopped 2019-12-17T20:02:02.713Z,1576612922.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T20:02:02.714Z,1576612922.714 [Default:CheckIn:D] Running Loop=1 2019-12-17T20:02:03.127Z,1576612923.127 [Default:CheckIn:D] Stopped 2019-12-17T20:02:03.127Z,1576612923.127 [Default:CheckIn:E] Running Loop=1 2019-12-17T20:02:03.516Z,1576612923.516 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.139901 min 2019-12-17T20:02:03.516Z,1576612923.516 [Default:CheckIn:E] Stopped 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn] Stopped 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn](INFO): Running loop #16 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn] Running Loop=16 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T20:02:03.517Z,1576612923.517 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T20:02:05.507Z,1576612925.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200209.00,A,3648.16384,N,12147.28238,W,0.175,151.91,171219,,,D*70 2019-12-17T20:02:05.509Z,1576612925.509 [NAL9602](INFO): GPS fix at 20191217T200209: (36.802731, -121.788040) 2019-12-17T20:02:05.555Z,1576612925.555 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T20:02:05.555Z,1576612925.555 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T20:02:12.406Z,1576612932.406 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0039.lzma 2019-12-17T20:02:14.412Z,1576612934.412 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0039.lzma.bak 2019-12-17T20:02:14.412Z,1576612934.412 [DataOverHttps](INFO): SBD MOMSN=12178354 2019-12-17T20:02:31.630Z,1576612951.630 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191217T185039/Express0040.lzma 2019-12-17T20:02:33.636Z,1576612953.636 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0040.lzma.bak 2019-12-17T20:02:33.637Z,1576612953.637 [DataOverHttps](INFO): SBD MOMSN=12178357 2019-12-17T20:02:35.001Z,1576612955.001 [NAL9602](INFO): SBD MO Status=2, MOMSN=4015, MT Status=2, MTMSN=0 2019-12-17T20:02:35.001Z,1576612955.001 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T20:02:35.047Z,1576612955.047 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T20:02:35.047Z,1576612955.047 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T20:02:35.047Z,1576612955.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T20:03:31.560Z,1576613011.560 [NAL9602](INFO): SBD MO Status=2, MOMSN=4015, MT Status=2, MTMSN=0 2019-12-17T20:03:31.560Z,1576613011.560 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-12-17T20:06:59.262Z,1576613219.262 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:07:05.699Z,1576613225.699 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:07:07.704Z,1576613227.704 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-12-17T20:07:21.058Z,1576613241.058 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:07:35.610Z,1576613255.610 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T20:07:35.610Z,1576613255.610 [Default:CheckIn:C.Wait] Stopped 2019-12-17T20:07:35.610Z,1576613255.610 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T20:07:35.611Z,1576613255.611 [Default:CheckIn:D] Running Loop=1 2019-12-17T20:07:36.009Z,1576613256.009 [Default:CheckIn:D] Stopped 2019-12-17T20:07:36.009Z,1576613256.009 [Default:CheckIn:E] Running Loop=1 2019-12-17T20:07:36.427Z,1576613256.427 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.687931 min 2019-12-17T20:07:36.427Z,1576613256.427 [Default:CheckIn:E] Stopped 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn] Stopped 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn](INFO): Running loop #17 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn] Running Loop=17 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T20:07:36.428Z,1576613256.428 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T20:07:37.218Z,1576613257.218 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:07:38.405Z,1576613258.405 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200742.00,A,3648.16360,N,12147.28260,W,0.797,142.13,171219,,,A*7A 2019-12-17T20:07:38.407Z,1576613258.407 [NAL9602](INFO): GPS fix at 20191217T200742: (36.802727, -121.788043) 2019-12-17T20:07:38.432Z,1576613258.432 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T20:07:38.432Z,1576613258.432 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T20:07:45.806Z,1576613265.806 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20191217T185039/Courier0042.lzma 2019-12-17T20:07:47.812Z,1576613267.812 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0042.lzma.bak 2019-12-17T20:07:47.812Z,1576613267.812 [DataOverHttps](INFO): SBD MOMSN=12178367 2019-12-17T20:07:52.159Z,1576613272.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:08:05.170Z,1576613285.170 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20191217T185039/Express0043.lzma 2019-12-17T20:08:07.113Z,1576613287.113 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:08:07.176Z,1576613287.176 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0043.lzma.bak 2019-12-17T20:08:07.176Z,1576613287.176 [DataOverHttps](INFO): SBD MOMSN=12178373 2019-12-17T20:08:08.329Z,1576613288.329 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T20:08:08.329Z,1576613288.329 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T20:08:08.330Z,1576613288.330 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T20:08:10.723Z,1576613290.723 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T20:08:22.862Z,1576613302.862 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:08:38.215Z,1576613318.215 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:08:53.172Z,1576613333.172 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:09:08.519Z,1576613348.519 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:09:23.463Z,1576613363.463 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-12-17T20:09:34.377Z,1576613374.377 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-12-17T20:09:34.404Z,1576613374.404 [BPC1](INFO): Received data from all battery sticks. 2019-12-17T20:09:56.175Z,1576613396.175 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-12-17T20:09:56.175Z,1576613396.175 [DropWeight] Hardware Fault, FailCount= 1 2019-12-17T20:09:56.175Z,1576613396.175 [DropWeight](ERROR): Hardware Fault 2019-12-17T20:09:56.205Z,1576613396.205 [CommandLine](FAULT): Scheduling is paused 2019-12-17T20:09:56.205Z,1576613396.205 [CBIT](INFO): Critical error at 20191217T200956 2019-12-17T20:09:56.208Z,1576613396.208 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-12-17T20:09:56.208Z,1576613396.208 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-12-17T20:09:56.616Z,1576613396.616 [CBIT](INFO): Critical error at 20191217T200956 2019-12-17T20:13:08.910Z,1576613588.910 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T20:13:08.910Z,1576613588.910 [Default:CheckIn:C.Wait] Stopped 2019-12-17T20:13:08.910Z,1576613588.910 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T20:13:08.911Z,1576613588.911 [Default:CheckIn:D] Running Loop=1 2019-12-17T20:13:09.316Z,1576613589.316 [Default:CheckIn:D] Stopped 2019-12-17T20:13:09.316Z,1576613589.316 [Default:CheckIn:E] Running Loop=1 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.243058 min 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn:E] Stopped 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn] Stopped 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn](INFO): Running loop #18 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn] Running Loop=18 2019-12-17T20:13:09.731Z,1576613589.731 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T20:13:09.732Z,1576613589.732 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T20:13:11.717Z,1576613591.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201315.00,A,3648.17004,N,12147.28518,W,1.244,3.06,171219,,,A*7F 2019-12-17T20:13:11.719Z,1576613591.719 [NAL9602](INFO): GPS fix at 20191217T201315: (36.802834, -121.788086) 2019-12-17T20:13:11.768Z,1576613591.768 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T20:13:11.768Z,1576613591.768 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T20:13:19.058Z,1576613599.058 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20191217T185039/Courier0045.lzma 2019-12-17T20:13:21.064Z,1576613601.064 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0045.lzma.bak 2019-12-17T20:13:21.064Z,1576613601.064 [DataOverHttps](INFO): SBD MOMSN=12178386 2019-12-17T20:13:37.914Z,1576613617.914 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20191217T185039/Express0046.lzma 2019-12-17T20:13:39.920Z,1576613619.920 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0046.lzma.bak 2019-12-17T20:13:39.920Z,1576613619.920 [DataOverHttps](INFO): SBD MOMSN=12178391 2019-12-17T20:13:41.232Z,1576613621.232 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T20:13:41.232Z,1576613621.232 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T20:13:41.232Z,1576613621.232 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T20:13:42.419Z,1576613622.419 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2019-12-17T20:13:42.494Z,1576613622.494 [NAL9602](FAULT): received: +CSQ:0 OK15, 2, 0, 0, 0 OK 2019-12-17T20:13:42.494Z,1576613622.494 [NAL9602] Data Fault, FailCount= 1 2019-12-17T20:13:42.494Z,1576613622.494 [NAL9602](ERROR): Data Fault 2019-12-17T20:13:42.553Z,1576613622.553 [CBIT](ERROR): Data Fault in component: NAL9602 2019-12-17T20:13:42.824Z,1576613622.824 [NAL9602](INFO): Powering down 2019-12-17T20:13:43.223Z,1576613623.223 [NAL9602](FAULT): LCB fault: Software Overcurrent. 2019-12-17T20:13:43.223Z,1576613623.223 [NAL9602] Hardware Fault, FailCount= 1 2019-12-17T20:13:43.223Z,1576613623.223 [NAL9602](ERROR): Hardware Fault 2019-12-17T20:13:43.710Z,1576613623.710 [CBIT](INFO): Clearing failed state for component NAL9602 2019-12-17T20:13:43.710Z,1576613623.710 [NAL9602] No Fault, FailCount= 1 2019-12-17T20:14:01.406Z,1576613641.406 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-12-17T20:14:01.406Z,1576613641.406 [AHRS_M2] Data Fault, FailCount= 1 2019-12-17T20:14:01.406Z,1576613641.406 [AHRS_M2](ERROR): Data Fault 2019-12-17T20:14:01.485Z,1576613641.485 [CBIT](ERROR): Data Fault in component: AHRS_M2 2019-12-17T20:14:01.794Z,1576613641.794 [AHRS_M2](INFO): Powering down 2019-12-17T20:14:02.646Z,1576613642.646 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:14:02.646Z,1576613642.646 [AHRS_M2] No Fault, FailCount= 1 2019-12-17T20:14:03.006Z,1576613643.006 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:14:06.562Z,1576613646.562 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:06.966Z,1576613646.966 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:07.374Z,1576613647.374 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:07.774Z,1576613647.774 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:08.178Z,1576613648.178 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:08.582Z,1576613648.582 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:08.994Z,1576613648.994 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:09.390Z,1576613649.390 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:09.794Z,1576613649.794 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:10.198Z,1576613650.198 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:10.602Z,1576613650.602 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:10.998Z,1576613650.998 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:11.411Z,1576613651.411 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:11.814Z,1576613651.814 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:12.222Z,1576613652.222 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:12.810Z,1576613652.810 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:13.178Z,1576613653.178 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:13.187Z,1576613653.187 [NAL9602](INFO): Powering up NAL9602 2019-12-17T20:14:13.582Z,1576613653.582 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:13.986Z,1576613653.986 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:14.390Z,1576613654.390 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:14.806Z,1576613654.806 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:15.210Z,1576613655.210 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:15.602Z,1576613655.602 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:16.006Z,1576613656.006 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:16.006Z,1576613656.006 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:14:16.006Z,1576613656.006 [AHRS_M2] Communications Fault, FailCount= 2 2019-12-17T20:14:16.007Z,1576613656.007 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:14:16.065Z,1576613656.065 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:14:16.098Z,1576613656.098 [AHRS_M2](INFO): Powering down 2019-12-17T20:14:16.940Z,1576613656.940 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:14:16.940Z,1576613656.940 [AHRS_M2] No Fault, FailCount= 2 2019-12-17T20:14:17.310Z,1576613657.310 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:14:20.866Z,1576613660.866 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:21.274Z,1576613661.274 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:21.690Z,1576613661.690 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:22.094Z,1576613662.094 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:22.498Z,1576613662.498 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:22.902Z,1576613662.902 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:23.306Z,1576613663.306 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:23.710Z,1576613663.710 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:24.118Z,1576613664.118 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:24.127Z,1576613664.127 [NAL9602](INFO): NAL9602 initialized 2019-12-17T20:14:24.518Z,1576613664.518 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:24.922Z,1576613664.922 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:25.330Z,1576613665.330 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:25.734Z,1576613665.734 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:26.134Z,1576613666.134 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:26.538Z,1576613666.538 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:26.954Z,1576613666.954 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:27.354Z,1576613667.354 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:27.750Z,1576613667.750 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:28.154Z,1576613668.154 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:28.558Z,1576613668.558 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:28.962Z,1576613668.962 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:29.366Z,1576613669.366 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:29.770Z,1576613669.770 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:30.178Z,1576613670.178 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:30.178Z,1576613670.178 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:14:30.178Z,1576613670.178 [AHRS_M2] Communications Fault, FailCount= 3 2019-12-17T20:14:30.179Z,1576613670.179 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:14:30.236Z,1576613670.236 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:14:30.254Z,1576613670.254 [AHRS_M2](INFO): Powering down 2019-12-17T20:14:31.103Z,1576613671.103 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:14:31.103Z,1576613671.103 [AHRS_M2] No Fault, FailCount= 3 2019-12-17T20:14:31.466Z,1576613671.466 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:14:35.022Z,1576613675.022 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:35.430Z,1576613675.430 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:35.830Z,1576613675.830 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:36.226Z,1576613676.226 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:36.638Z,1576613676.638 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:37.042Z,1576613677.042 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:37.462Z,1576613677.462 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:37.858Z,1576613677.858 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:38.266Z,1576613678.266 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:38.666Z,1576613678.666 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:39.070Z,1576613679.070 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:39.478Z,1576613679.478 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:39.882Z,1576613679.882 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:40.286Z,1576613680.286 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:40.690Z,1576613680.690 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:41.098Z,1576613681.098 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:41.498Z,1576613681.498 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:41.902Z,1576613681.902 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:42.307Z,1576613682.307 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:42.722Z,1576613682.722 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:43.118Z,1576613683.118 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:43.518Z,1576613683.518 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:43.922Z,1576613683.922 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:44.326Z,1576613684.326 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:44.326Z,1576613684.326 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:14:44.326Z,1576613684.326 [AHRS_M2] Communications Fault, FailCount= 4 2019-12-17T20:14:44.327Z,1576613684.327 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:14:44.389Z,1576613684.389 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:14:44.406Z,1576613684.406 [AHRS_M2](INFO): Powering down 2019-12-17T20:14:45.260Z,1576613685.260 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:14:45.260Z,1576613685.260 [AHRS_M2] No Fault, FailCount= 4 2019-12-17T20:14:45.618Z,1576613685.618 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:14:49.174Z,1576613689.174 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:49.582Z,1576613689.582 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:49.974Z,1576613689.974 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:50.386Z,1576613690.386 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:50.794Z,1576613690.794 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:51.210Z,1576613691.210 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:51.606Z,1576613691.606 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:52.002Z,1576613692.002 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:52.406Z,1576613692.406 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:52.822Z,1576613692.822 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:53.234Z,1576613693.234 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:53.638Z,1576613693.638 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:54.047Z,1576613694.047 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:54.458Z,1576613694.458 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:54.862Z,1576613694.862 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:55.266Z,1576613695.266 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:55.271Z,1576613695.271 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T20:14:55.702Z,1576613695.702 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:56.114Z,1576613696.114 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:56.538Z,1576613696.538 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:56.950Z,1576613696.950 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:57.366Z,1576613697.366 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:57.766Z,1576613697.766 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:58.158Z,1576613698.158 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:58.562Z,1576613698.562 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:14:58.562Z,1576613698.562 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:14:58.562Z,1576613698.562 [AHRS_M2] Communications Fault, FailCount= 5 2019-12-17T20:14:58.563Z,1576613698.563 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:14:58.618Z,1576613698.618 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:14:58.618Z,1576613698.618 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2019-12-17T20:14:58.650Z,1576613698.650 [AHRS_M2](INFO): Powering down 2019-12-17T20:14:58.757Z,1576613698.757 [CBIT](INFO): Critical error at 20191217T201458 2019-12-17T20:16:01.295Z,1576613761.295 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-12-17T20:16:01.295Z,1576613761.295 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-12-17T20:16:01.295Z,1576613761.295 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-17T20:16:01.331Z,1576613761.331 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-17T20:16:01.708Z,1576613761.708 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-17T20:16:01.708Z,1576613761.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 0 2019-12-17T20:18:02.502Z,1576613882.502 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-12-17T20:18:02.502Z,1576613882.502 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2019-12-17T20:18:02.502Z,1576613882.502 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-17T20:18:02.513Z,1576613882.513 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-17T20:18:02.907Z,1576613882.907 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-17T20:18:02.907Z,1576613882.907 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2019-12-17T20:18:41.764Z,1576613921.764 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-12-17T20:18:41.764Z,1576613921.764 [Default:CheckIn:C.Wait] Stopped 2019-12-17T20:18:41.764Z,1576613921.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T20:18:41.764Z,1576613921.764 [Default:CheckIn:D] Running Loop=1 2019-12-17T20:18:42.112Z,1576613922.112 [Default:CheckIn:D] Stopped 2019-12-17T20:18:42.113Z,1576613922.113 [Default:CheckIn:E] Running Loop=1 2019-12-17T20:18:42.564Z,1576613922.564 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.789665 min 2019-12-17T20:18:42.564Z,1576613922.564 [Default:CheckIn:E] Stopped 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn] Stopped 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn](INFO): Running loop #19 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn] Running Loop=19 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-12-17T20:18:42.565Z,1576613922.565 [Default:CheckIn:Read_GPS] Running Loop=1 2019-12-17T20:18:46.107Z,1576613926.107 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2019-12-17T20:19:58.931Z,1576613998.931 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:19:58.931Z,1576613998.931 [AHRS_M2] No Fault, FailCount= 5 2019-12-17T20:19:59.227Z,1576613999.227 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:20:02.783Z,1576614002.783 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:03.262Z,1576614003.262 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:03.322Z,1576614003.322 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-12-17T20:20:03.322Z,1576614003.322 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2019-12-17T20:20:03.322Z,1576614003.322 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-17T20:20:03.385Z,1576614003.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-17T20:20:03.763Z,1576614003.763 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:03.872Z,1576614003.872 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-17T20:20:03.872Z,1576614003.872 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2019-12-17T20:20:04.202Z,1576614004.202 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:04.634Z,1576614004.634 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:05.066Z,1576614005.066 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:05.495Z,1576614005.495 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:05.922Z,1576614005.922 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:06.350Z,1576614006.351 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:06.743Z,1576614006.743 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:07.158Z,1576614007.158 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:07.570Z,1576614007.570 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:07.975Z,1576614007.975 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:08.394Z,1576614008.394 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:08.810Z,1576614008.810 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:09.235Z,1576614009.235 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:09.654Z,1576614009.654 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:10.079Z,1576614010.079 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:10.490Z,1576614010.490 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:10.918Z,1576614010.918 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:11.346Z,1576614011.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:11.750Z,1576614011.750 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:12.158Z,1576614012.158 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:12.159Z,1576614012.159 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:20:12.159Z,1576614012.159 [AHRS_M2] Communications Fault, FailCount= 1 2019-12-17T20:20:12.159Z,1576614012.159 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:20:12.249Z,1576614012.249 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:20:12.263Z,1576614012.263 [AHRS_M2](INFO): Powering down 2019-12-17T20:20:13.150Z,1576614013.150 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:20:13.150Z,1576614013.150 [AHRS_M2] No Fault, FailCount= 1 2019-12-17T20:20:13.470Z,1576614013.470 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:20:17.026Z,1576614017.026 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:17.478Z,1576614017.478 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:17.930Z,1576614017.930 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:18.415Z,1576614018.415 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:18.823Z,1576614018.823 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:19.286Z,1576614019.286 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:19.718Z,1576614019.718 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:20.159Z,1576614020.159 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:20.582Z,1576614020.582 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:21.006Z,1576614021.006 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:21.418Z,1576614021.418 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:21.822Z,1576614021.822 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:22.230Z,1576614022.230 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:22.670Z,1576614022.670 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:23.106Z,1576614023.106 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:23.514Z,1576614023.514 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:23.930Z,1576614023.930 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:24.346Z,1576614024.346 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:24.747Z,1576614024.747 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:25.166Z,1576614025.166 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:25.574Z,1576614025.574 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:25.994Z,1576614025.994 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:26.414Z,1576614026.414 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:26.414Z,1576614026.414 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:20:26.415Z,1576614026.415 [AHRS_M2] Communications Fault, FailCount= 2 2019-12-17T20:20:26.415Z,1576614026.415 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:20:26.516Z,1576614026.516 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:20:26.525Z,1576614026.525 [AHRS_M2](INFO): Powering down 2019-12-17T20:20:27.397Z,1576614027.397 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:20:27.397Z,1576614027.397 [AHRS_M2] No Fault, FailCount= 2 2019-12-17T20:20:27.730Z,1576614027.730 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:20:31.286Z,1576614031.286 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:31.727Z,1576614031.727 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:32.135Z,1576614032.135 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:32.563Z,1576614032.563 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:32.978Z,1576614032.978 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:33.395Z,1576614033.395 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:33.798Z,1576614033.798 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:34.254Z,1576614034.254 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:34.675Z,1576614034.675 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:35.098Z,1576614035.098 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:35.527Z,1576614035.527 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:35.955Z,1576614035.955 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:36.364Z,1576614036.364 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:36.775Z,1576614036.775 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:37.190Z,1576614037.190 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:37.654Z,1576614037.654 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:38.071Z,1576614038.071 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:38.498Z,1576614038.498 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:38.975Z,1576614038.975 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:39.403Z,1576614039.403 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:39.810Z,1576614039.810 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:40.238Z,1576614040.238 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:40.655Z,1576614040.655 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:40.655Z,1576614040.655 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:20:40.655Z,1576614040.655 [AHRS_M2] Communications Fault, FailCount= 3 2019-12-17T20:20:40.655Z,1576614040.655 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:20:40.745Z,1576614040.745 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:20:40.750Z,1576614040.750 [AHRS_M2](INFO): Powering down 2019-12-17T20:20:41.612Z,1576614041.612 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:20:41.612Z,1576614041.612 [AHRS_M2] No Fault, FailCount= 3 2019-12-17T20:20:41.963Z,1576614041.963 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:20:45.507Z,1576614045.507 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:45.922Z,1576614045.922 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:46.350Z,1576614046.350 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:46.739Z,1576614046.739 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:47.170Z,1576614047.170 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:47.590Z,1576614047.590 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:48.014Z,1576614048.014 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:48.486Z,1576614048.486 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:48.895Z,1576614048.895 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:49.342Z,1576614049.342 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:49.770Z,1576614049.770 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:50.202Z,1576614050.202 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:50.642Z,1576614050.642 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:51.106Z,1576614051.106 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:51.551Z,1576614051.551 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:51.990Z,1576614051.990 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:52.426Z,1576614052.426 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:52.867Z,1576614052.867 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:53.342Z,1576614053.342 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:53.794Z,1576614053.794 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:54.211Z,1576614054.211 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:54.638Z,1576614054.638 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:55.054Z,1576614055.054 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:20:55.054Z,1576614055.054 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:20:55.055Z,1576614055.055 [AHRS_M2] Communications Fault, FailCount= 4 2019-12-17T20:20:55.055Z,1576614055.055 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:20:55.149Z,1576614055.149 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:20:55.155Z,1576614055.155 [AHRS_M2](INFO): Powering down 2019-12-17T20:20:56.060Z,1576614056.060 [CBIT](INFO): Clearing failed state for component AHRS_M2 2019-12-17T20:20:56.060Z,1576614056.060 [AHRS_M2] No Fault, FailCount= 4 2019-12-17T20:20:56.366Z,1576614056.366 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-12-17T20:20:59.924Z,1576614059.924 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:00.334Z,1576614060.334 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:00.742Z,1576614060.742 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:01.166Z,1576614061.166 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:01.587Z,1576614061.587 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:01.990Z,1576614061.990 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:02.402Z,1576614062.402 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:02.823Z,1576614062.823 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:03.230Z,1576614063.230 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:03.622Z,1576614063.622 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:04.042Z,1576614064.042 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:04.466Z,1576614064.466 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:04.903Z,1576614064.903 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:05.302Z,1576614065.302 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:05.718Z,1576614065.718 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:06.147Z,1576614066.147 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:06.555Z,1576614066.555 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:07.006Z,1576614067.006 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:07.435Z,1576614067.435 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:07.859Z,1576614067.859 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:08.298Z,1576614068.298 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:08.706Z,1576614068.706 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:09.115Z,1576614069.115 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:09.510Z,1576614069.510 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2019-12-17T20:21:09.510Z,1576614069.510 [AHRS_M2](FAULT): Failed to initialize within timeout. 2019-12-17T20:21:09.511Z,1576614069.511 [AHRS_M2] Communications Fault, FailCount= 5 2019-12-17T20:21:09.511Z,1576614069.511 [AHRS_M2](ERROR): Communications Fault 2019-12-17T20:21:09.605Z,1576614069.605 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2019-12-17T20:21:09.605Z,1576614069.605 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2019-12-17T20:21:09.610Z,1576614069.610 [AHRS_M2](INFO): Powering down 2019-12-17T20:21:09.756Z,1576614069.756 [CBIT](INFO): Critical error at 20191217T202109 2019-12-17T20:22:04.609Z,1576614124.609 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-12-17T20:22:04.609Z,1576614124.609 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2019-12-17T20:22:04.609Z,1576614124.609 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-17T20:22:04.649Z,1576614124.649 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-17T20:22:05.035Z,1576614125.035 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-17T20:22:05.035Z,1576614125.035 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2019-12-17T20:23:42.777Z,1576614222.777 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-12-17T20:18:42.6Z 2019-12-17T20:23:42.777Z,1576614222.777 [Default:CheckIn:Read_GPS] Stopped 2019-12-17T20:23:42.778Z,1576614222.778 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-17T20:23:54.426Z,1576614234.426 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20191217T185039/Courier0048.lzma 2019-12-17T20:23:56.432Z,1576614236.432 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Courier0048.lzma.bak 2019-12-17T20:23:56.432Z,1576614236.432 [DataOverHttps](INFO): SBD MOMSN=12178413 2019-12-17T20:24:05.457Z,1576614245.457 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2019-12-17T20:24:05.457Z,1576614245.457 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2019-12-17T20:24:05.458Z,1576614245.458 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-17T20:24:05.531Z,1576614245.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-17T20:24:05.900Z,1576614245.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-17T20:24:05.900Z,1576614245.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2019-12-17T20:24:13.493Z,1576614253.493 [NAL9602](INFO): Not Powering down - fast GPS 2019-12-17T20:24:21.303Z,1576614261.303 [DataOverHttps](INFO): Sending 496 bytes from file Logs/20191217T185039/Express0049.lzma 2019-12-17T20:24:23.309Z,1576614263.309 [DataOverHttps](INFO): Moved sent file to Logs/20191217T185039/Express0049.lzma.bak 2019-12-17T20:24:23.309Z,1576614263.309 [DataOverHttps](INFO): SBD MOMSN=12178417 2019-12-17T20:24:24.848Z,1576614264.848 [Default:CheckIn:Read_Iridium] Stopped 2019-12-17T20:24:24.849Z,1576614264.849 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-17T20:24:24.849Z,1576614264.849 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-17T20:24:56.388Z,1576614296.388 [CBIT](INFO): Clearing failed state for component DropWeight 2019-12-17T20:24:56.388Z,1576614296.388 [DropWeight] No Fault, FailCount= 1 2019-12-17T20:25:46.833Z,1576614346.833 [CommandLine](IMPORTANT): got command quit 2019-12-17T20:25:47.883Z,1576614347.883 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:47.883Z,1576614347.883 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:47.978Z,1576614347.978 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-12-17T20:25:47.979Z,1576614347.979 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:47.980Z,1576614347.980 [CommandLine](INFO): Join timeout helper Thread ID is 3785 2019-12-17T20:25:47.981Z,1576614347.981 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-12-17T20:25:47.981Z,1576614347.981 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:47.982Z,1576614347.982 [NavChartDb](INFO): Join timeout helper Thread ID is 3786 2019-12-17T20:25:48.251Z,1576614348.251 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:48.251Z,1576614348.251 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:48.255Z,1576614348.255 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-12-17T20:25:48.255Z,1576614348.255 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:48.255Z,1576614348.255 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3787 2019-12-17T20:25:48.399Z,1576614348.399 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:48.399Z,1576614348.399 [WetLabsBB2FL](INFO): Powering down 2019-12-17T20:25:48.400Z,1576614348.400 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:48.407Z,1576614348.407 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-12-17T20:25:48.407Z,1576614348.407 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:48.408Z,1576614348.408 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3788 2019-12-17T20:25:49.191Z,1576614349.191 [CTD_Seabird](INFO): Powering down 2019-12-17T20:25:49.202Z,1576614349.202 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:49.203Z,1576614349.203 [CTD_Seabird](INFO): Powering down 2019-12-17T20:25:49.215Z,1576614349.215 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.234Z,1576614349.234 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-12-17T20:25:49.234Z,1576614349.234 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.236Z,1576614349.236 [Radio_Surface](INFO): Join timeout helper Thread ID is 3789 2019-12-17T20:25:49.374Z,1576614349.374 [Radio_Surface](INFO): Powering down 2019-12-17T20:25:49.375Z,1576614349.375 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:49.376Z,1576614349.376 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.379Z,1576614349.379 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-12-17T20:25:49.380Z,1576614349.380 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.380Z,1576614349.380 [DataOverHttps](INFO): Join timeout helper Thread ID is 3790 2019-12-17T20:25:49.446Z,1576614349.446 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:49.447Z,1576614349.447 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.455Z,1576614349.455 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-12-17T20:25:49.455Z,1576614349.455 [logger ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.456Z,1576614349.456 [logger](INFO): Join timeout helper Thread ID is 3791 2019-12-17T20:25:49.502Z,1576614349.502 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:49.503Z,1576614349.503 [logger ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.515Z,1576614349.515 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-12-17T20:25:49.515Z,1576614349.515 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.515Z,1576614349.515 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-12-17T20:25:49.515Z,1576614349.515 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:49.516Z,1576614349.516 [controlThread](INFO): Join timeout helper Thread ID is 3792 2019-12-17T20:25:49.643Z,1576614349.643 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-17T20:25:49.643Z,1576614349.643 [controlThread](DEBUG): Uninitializing ControlThread 2019-12-17T20:25:49.643Z,1576614349.643 [AHRS_M2](INFO): Powering down 2019-12-17T20:25:49.645Z,1576614349.645 [NAL9602](INFO): Powering down 2019-12-17T20:25:49.647Z,1576614349.647 [DAT](INFO): Powering down 2019-12-17T20:25:49.767Z,1576614349.767 [Aanderaa_O2](INFO): Powering down 2019-12-17T20:25:49.769Z,1576614349.769 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-12-17T20:25:49.771Z,1576614349.771 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-12-17T20:25:49.771Z,1576614349.771 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-12-17T20:25:49.772Z,1576614349.772 [MissionManager](INFO): Uninitializing Mission Default 2019-12-17T20:25:49.772Z,1576614349.772 [Default] Stopped 2019-12-17T20:25:49.772Z,1576614349.772 [Default](DEBUG): Aggregate::uninitialize Default 2019-12-17T20:25:49.772Z,1576614349.772 [Default:B.GoToSurface] Stopped 2019-12-17T20:25:49.772Z,1576614349.772 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-12-17T20:25:49.772Z,1576614349.772 [Default:CheckIn] Stopped 2019-12-17T20:25:49.772Z,1576614349.772 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-17T20:25:49.772Z,1576614349.772 [Default:CheckIn:C.Wait] Stopped 2019-12-17T20:25:49.772Z,1576614349.772 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-17T20:25:49.775Z,1576614349.775 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-12-17T20:25:49.775Z,1576614349.775 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-12-17T20:25:49.775Z,1576614349.775 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-12-17T20:25:49.775Z,1576614349.775 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-12-17T20:25:49.776Z,1576614349.776 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-12-17T20:25:49.776Z,1576614349.776 [BuoyancyServo](INFO): Powering down 2019-12-17T20:25:49.791Z,1576614349.791 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-12-17T20:25:49.791Z,1576614349.791 [ElevatorServo](INFO): Powering down 2019-12-17T20:25:49.792Z,1576614349.792 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-12-17T20:25:49.792Z,1576614349.792 [MassServo](INFO): Powering down 2019-12-17T20:25:49.792Z,1576614349.792 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-12-17T20:25:49.793Z,1576614349.793 [RudderServo](INFO): Powering down 2019-12-17T20:25:49.793Z,1576614349.793 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-12-17T20:25:49.793Z,1576614349.793 [ThrusterServo](INFO): Powering down 2019-12-17T20:25:49.794Z,1576614349.794 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-12-17T20:25:49.795Z,1576614349.795 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-12-17T20:25:49.795Z,1576614349.795 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-12-17T20:25:49.795Z,1576614349.795 [CBIT](DEBUG): Powering off loads. 2019-12-17T20:25:49.806Z,1576614349.806 [CBIT](DEBUG): Disabling WDT. 2019-12-17T20:25:49.818Z,1576614349.818 [CBIT](DEBUG): Opening all GF detection circuits. 2019-12-17T20:25:49.819Z,1576614349.819 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.020Z,1576614350.020 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.035Z,1576614350.035 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.141Z,1576614350.141 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.220Z,1576614350.220 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.318Z,1576614350.318 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-17T20:25:50.443Z,1576614350.443 [logger ThreadHandler](INFO): Thread cancelled.