2019-10-10T21:37:14.193Z,1570743434.193 [CommandLine](IMPORTANT): got command restart logs 2019-10-10T21:37:37.247Z,1570743457.247 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T21:37:37.247Z,1570743457.247 [Default:CheckIn:C.Wait] Stopped 2019-10-10T21:37:37.247Z,1570743457.247 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T21:37:37.247Z,1570743457.247 [Default:CheckIn:D] Running Loop=1 2019-10-10T21:37:37.664Z,1570743457.664 [Default:CheckIn:D] Stopped 2019-10-10T21:37:37.664Z,1570743457.664 [Default:CheckIn:E] Running Loop=1 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.700632 min 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn:E] Stopped 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn] Stopped 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn](INFO): Running loop #2 2019-10-10T21:37:38.045Z,1570743458.045 [Default:CheckIn] Running Loop=2 2019-10-10T21:37:38.046Z,1570743458.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T21:37:38.046Z,1570743458.046 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T21:37:39.649Z,1570743459.649 [NAL9602](DEBUG): Fix Requested 2019-10-10T21:37:40.048Z,1570743460.048 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213651.00,A,3648.39653,N,12149.59494,W,1.205,110.55,101019,,,D*7C 2019-10-10T21:37:40.064Z,1570743460.064 [NAL9602](INFO): GPS fix at 20191010T213651: (36.806609, -121.826582) 2019-10-10T21:37:40.089Z,1570743460.089 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T21:37:40.089Z,1570743460.089 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T21:37:42.084Z,1570743462.084 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T21:37:49.512Z,1570743469.512 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191010T072413/Courier0130.lzma 2019-10-10T21:37:51.519Z,1570743471.519 [DataOverHttps](INFO): Moved sent file to Logs/20191010T072413/Courier0130.lzma.bak 2019-10-10T21:37:51.519Z,1570743471.519 [DataOverHttps](INFO): SBD MOMSN=11872622 2019-10-10T21:38:11.197Z,1570743491.197 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20191010T213714/Courier0000.lzma 2019-10-10T21:38:13.202Z,1570743493.202 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0000.lzma.bak 2019-10-10T21:38:13.202Z,1570743493.202 [DataOverHttps](INFO): SBD MOMSN=11872624 2019-10-10T21:38:30.543Z,1570743510.543 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0 2019-10-10T21:38:30.543Z,1570743510.543 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:38:31.776Z,1570743511.776 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20191010T213714/Courier0003.lzma 2019-10-10T21:38:33.783Z,1570743513.783 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0003.lzma.bak 2019-10-10T21:38:33.783Z,1570743513.783 [DataOverHttps](INFO): SBD MOMSN=11872628 2019-10-10T21:38:55.572Z,1570743535.572 [DataOverHttps](INFO): Sending 95 bytes from file Logs/20191010T072413/Express0131.lzma 2019-10-10T21:38:57.579Z,1570743537.579 [DataOverHttps](INFO): Moved sent file to Logs/20191010T072413/Express0131.lzma.bak 2019-10-10T21:38:57.579Z,1570743537.579 [DataOverHttps](INFO): SBD MOMSN=11872633 2019-10-10T21:39:23.245Z,1570743563.245 [DataOverHttps](INFO): Sending 325 bytes from file Logs/20191010T213714/Express0001.lzma 2019-10-10T21:39:25.250Z,1570743565.250 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0001.lzma.bak 2019-10-10T21:39:25.250Z,1570743565.250 [DataOverHttps](INFO): SBD MOMSN=11872640 2019-10-10T21:39:44.980Z,1570743584.980 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191010T213714/Express0004.lzma 2019-10-10T21:39:46.986Z,1570743586.986 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0004.lzma.bak 2019-10-10T21:39:46.986Z,1570743586.986 [DataOverHttps](INFO): SBD MOMSN=11872644 2019-10-10T21:39:51.389Z,1570743591.389 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T21:39:51.389Z,1570743591.389 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T21:39:51.389Z,1570743591.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T21:39:52.971Z,1570743592.971 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0 2019-10-10T21:39:52.971Z,1570743592.971 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:40:41.451Z,1570743641.451 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0 2019-10-10T21:40:41.451Z,1570743641.451 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:41:02.471Z,1570743662.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0 2019-10-10T21:41:02.471Z,1570743662.471 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:41:38.023Z,1570743698.023 [NAL9602](INFO): SBD MO Status=2, MOMSN=3634, MT Status=2, MTMSN=0 2019-10-10T21:41:38.023Z,1570743698.023 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:42:16.447Z,1570743736.447 [NAL9602](IMPORTANT): SBD MO Status=0, MOMSN=3634, MT Status=1, MTMSN=263 2019-10-10T21:42:16.447Z,1570743736.447 [NAL9602](INFO): Data available in MT queue 2019-10-10T21:42:16.945Z,1570743736.945 [NAL9602](INFO): Received command:stop 2019-10-10T21:42:16.950Z,1570743736.950 [CommandLine](IMPORTANT): got command stop 2019-10-10T21:42:47.570Z,1570743767.570 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T21:44:52.041Z,1570743892.041 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T21:44:52.041Z,1570743892.041 [Default:CheckIn:C.Wait] Stopped 2019-10-10T21:44:52.041Z,1570743892.041 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T21:44:52.042Z,1570743892.042 [Default:CheckIn:D] Running Loop=1 2019-10-10T21:44:52.454Z,1570743892.454 [Default:CheckIn:D] Stopped 2019-10-10T21:44:52.454Z,1570743892.454 [Default:CheckIn:E] Running Loop=1 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.947127 min 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn:E] Stopped 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn] Stopped 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T21:44:52.838Z,1570743892.838 [Default:CheckIn](INFO): Running loop #3 2019-10-10T21:44:52.839Z,1570743892.839 [Default:CheckIn] Running Loop=3 2019-10-10T21:44:52.839Z,1570743892.839 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T21:44:52.839Z,1570743892.839 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T21:44:54.449Z,1570743894.449 [NAL9602](DEBUG): Fix Requested 2019-10-10T21:44:54.829Z,1570743894.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214405.00,A,3648.44654,N,12147.11972,W,5.559,173.55,101019,,,D*7D 2019-10-10T21:44:54.831Z,1570743894.831 [NAL9602](INFO): GPS fix at 20191010T214405: (36.807442, -121.785329) 2019-10-10T21:44:54.873Z,1570743894.873 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T21:44:54.873Z,1570743894.873 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T21:44:56.914Z,1570743896.914 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T21:45:09.048Z,1570743909.048 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T213714/Courier0006.lzma 2019-10-10T21:45:12.144Z,1570743912.144 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0006.lzma.bak 2019-10-10T21:45:12.144Z,1570743912.144 [DataOverHttps](INFO): SBD MOMSN=11872648 2019-10-10T21:45:31.425Z,1570743931.425 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20191010T213714/Express0007.lzma 2019-10-10T21:45:33.430Z,1570743933.430 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0007.lzma.bak 2019-10-10T21:45:33.430Z,1570743933.430 [DataOverHttps](INFO): SBD MOMSN=11872651 2019-10-10T21:45:37.768Z,1570743937.768 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T21:45:37.768Z,1570743937.768 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T21:45:37.769Z,1570743937.769 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T21:45:44.579Z,1570743944.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=3635, MT Status=0, MTMSN=0 2019-10-10T21:45:44.579Z,1570743944.579 [NAL9602](INFO): No messages in MT queue 2019-10-10T21:46:15.282Z,1570743975.282 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T21:50:38.341Z,1570744238.341 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T21:50:38.341Z,1570744238.341 [Default:CheckIn:C.Wait] Stopped 2019-10-10T21:50:38.341Z,1570744238.341 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T21:50:38.341Z,1570744238.341 [Default:CheckIn:D] Running Loop=1 2019-10-10T21:50:38.744Z,1570744238.744 [Default:CheckIn:D] Stopped 2019-10-10T21:50:38.744Z,1570744238.744 [Default:CheckIn:E] Running Loop=1 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.718634 min 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn:E] Stopped 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn] Stopped 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn](INFO): Running loop #4 2019-10-10T21:50:39.156Z,1570744239.156 [Default:CheckIn] Running Loop=4 2019-10-10T21:50:39.157Z,1570744239.157 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T21:50:39.157Z,1570744239.157 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T21:50:40.751Z,1570744240.751 [NAL9602](DEBUG): Fix Requested 2019-10-10T21:50:41.143Z,1570744241.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214952.00,A,3648.16357,N,12147.21643,W,0.000,0.90,101019,,,A*78 2019-10-10T21:50:41.146Z,1570744241.146 [NAL9602](INFO): GPS fix at 20191010T214952: (36.802726, -121.786941) 2019-10-10T21:50:41.189Z,1570744241.189 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T21:50:41.189Z,1570744241.189 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T21:50:43.174Z,1570744243.174 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T21:50:50.045Z,1570744250.045 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191010T213714/Courier0009.lzma 2019-10-10T21:50:52.050Z,1570744252.050 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0009.lzma.bak 2019-10-10T21:50:52.050Z,1570744252.050 [DataOverHttps](INFO): SBD MOMSN=11872665 2019-10-10T21:51:18.016Z,1570744278.016 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0010.lzma 2019-10-10T21:51:20.022Z,1570744280.022 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0010.lzma.bak 2019-10-10T21:51:20.022Z,1570744280.022 [DataOverHttps](INFO): SBD MOMSN=11872668 2019-10-10T21:51:24.040Z,1570744284.040 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T21:51:24.045Z,1570744284.045 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T21:51:24.045Z,1570744284.045 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T21:53:03.365Z,1570744383.365 [NAL9602](INFO): SBD MO Status=2, MOMSN=3636, MT Status=2, MTMSN=0 2019-10-10T21:53:03.366Z,1570744383.366 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:53:19.527Z,1570744399.527 [NAL9602](INFO): SBD MO Status=2, MOMSN=3636, MT Status=2, MTMSN=0 2019-10-10T21:53:19.527Z,1570744399.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:53:32.055Z,1570744412.055 [NAL9602](INFO): SBD MO Status=2, MOMSN=3636, MT Status=2, MTMSN=0 2019-10-10T21:53:32.055Z,1570744412.055 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:54:24.979Z,1570744464.979 [NAL9602](INFO): SBD MO Status=2, MOMSN=3636, MT Status=2, MTMSN=0 2019-10-10T21:54:24.979Z,1570744464.979 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:55:21.959Z,1570744521.959 [NAL9602](INFO): SBD MO Status=0, MOMSN=3636, MT Status=0, MTMSN=0 2019-10-10T21:55:21.959Z,1570744521.959 [NAL9602](INFO): No messages in MT queue 2019-10-10T21:55:52.662Z,1570744552.662 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T21:56:24.616Z,1570744584.616 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T21:56:24.616Z,1570744584.616 [Default:CheckIn:C.Wait] Stopped 2019-10-10T21:56:24.616Z,1570744584.616 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T21:56:24.625Z,1570744584.625 [Default:CheckIn:D] Running Loop=1 2019-10-10T21:56:25.039Z,1570744585.039 [Default:CheckIn:D] Stopped 2019-10-10T21:56:25.039Z,1570744585.039 [Default:CheckIn:E] Running Loop=1 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.490206 min 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn:E] Stopped 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn] Stopped 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn](INFO): Running loop #5 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn] Running Loop=5 2019-10-10T21:56:25.455Z,1570744585.455 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T21:56:25.456Z,1570744585.456 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T21:56:27.013Z,1570744587.013 [NAL9602](DEBUG): Fix Requested 2019-10-10T21:56:27.404Z,1570744587.404 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215538.00,A,3648.14786,N,12147.22446,W,0.564,209.38,101019,,,D*7C 2019-10-10T21:56:27.406Z,1570744587.406 [NAL9602](INFO): GPS fix at 20191010T215538: (36.802464, -121.787074) 2019-10-10T21:56:27.430Z,1570744587.430 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T21:56:27.430Z,1570744587.430 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T21:56:29.030Z,1570744589.030 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T21:56:36.720Z,1570744596.720 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T213714/Courier0012.lzma 2019-10-10T21:56:38.727Z,1570744598.727 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0012.lzma.bak 2019-10-10T21:56:38.727Z,1570744598.727 [DataOverHttps](INFO): SBD MOMSN=11872677 2019-10-10T21:57:03.421Z,1570744623.421 [DataOverHttps](INFO): Sending 118 bytes from file Logs/20191010T213714/Express0013.lzma 2019-10-10T21:57:05.426Z,1570744625.426 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0013.lzma.bak 2019-10-10T21:57:05.427Z,1570744625.427 [DataOverHttps](INFO): SBD MOMSN=11872680 2019-10-10T21:57:09.526Z,1570744629.526 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T21:57:09.526Z,1570744629.526 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T21:57:09.526Z,1570744629.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T21:57:15.487Z,1570744635.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=3637, MT Status=2, MTMSN=0 2019-10-10T21:57:15.487Z,1570744635.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T21:57:51.039Z,1570744671.039 [NAL9602](INFO): SBD MO Status=0, MOMSN=3637, MT Status=0, MTMSN=0 2019-10-10T21:57:51.040Z,1570744671.040 [NAL9602](INFO): No messages in MT queue 2019-10-10T21:58:21.746Z,1570744701.746 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T22:02:10.066Z,1570744930.066 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T22:02:10.066Z,1570744930.066 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:02:10.066Z,1570744930.066 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:02:10.067Z,1570744930.067 [Default:CheckIn:D] Running Loop=1 2019-10-10T22:02:10.495Z,1570744930.495 [Default:CheckIn:D] Stopped 2019-10-10T22:02:10.495Z,1570744930.495 [Default:CheckIn:E] Running Loop=1 2019-10-10T22:02:10.857Z,1570744930.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.247817 min 2019-10-10T22:02:10.857Z,1570744930.857 [Default:CheckIn:E] Stopped 2019-10-10T22:02:10.857Z,1570744930.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T22:02:10.857Z,1570744930.857 [Default:CheckIn] Stopped 2019-10-10T22:02:10.857Z,1570744930.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:02:10.858Z,1570744930.858 [Default:CheckIn](INFO): Running loop #6 2019-10-10T22:02:10.858Z,1570744930.858 [Default:CheckIn] Running Loop=6 2019-10-10T22:02:10.858Z,1570744930.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T22:02:10.858Z,1570744930.858 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T22:02:12.455Z,1570744932.455 [NAL9602](DEBUG): Fix Requested 2019-10-10T22:02:12.851Z,1570744932.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220123.00,A,3648.17027,N,12147.27916,W,0.194,352.52,101019,,,A*7B 2019-10-10T22:02:12.854Z,1570744932.854 [NAL9602](INFO): GPS fix at 20191010T220123: (36.802838, -121.787986) 2019-10-10T22:02:12.896Z,1570744932.896 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T22:02:12.896Z,1570744932.896 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T22:02:14.874Z,1570744934.874 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T22:02:21.992Z,1570744941.992 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T213714/Courier0015.lzma 2019-10-10T22:02:23.998Z,1570744943.998 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0015.lzma.bak 2019-10-10T22:02:23.998Z,1570744943.999 [DataOverHttps](INFO): SBD MOMSN=11872692 2019-10-10T22:02:24.163Z,1570744944.163 [NAL9602](INFO): SBD MO Status=0, MOMSN=3638, MT Status=0, MTMSN=0 2019-10-10T22:02:24.164Z,1570744944.164 [NAL9602](INFO): No messages in MT queue 2019-10-10T22:02:35.109Z,1570744955.109 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2019-10-10T22:02:43.904Z,1570744963.904 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0016.lzma 2019-10-10T22:02:45.910Z,1570744965.910 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0016.lzma.bak 2019-10-10T22:02:45.910Z,1570744965.910 [DataOverHttps](INFO): SBD MOMSN=11872695 2019-10-10T22:02:50.494Z,1570744970.494 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T22:02:50.495Z,1570744970.495 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T22:02:50.495Z,1570744970.495 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T22:02:54.862Z,1570744974.862 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T22:05:05.041Z,1570745105.041 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:05:12.716Z,1570745112.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:05:27.665Z,1570745127.665 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:05:43.024Z,1570745143.024 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:05:58.380Z,1570745158.380 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:06:13.317Z,1570745173.317 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:06:28.668Z,1570745188.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:06:44.025Z,1570745204.025 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:07:00.178Z,1570745220.178 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:07:15.544Z,1570745235.544 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2019-10-10T22:07:36.155Z,1570745256.155 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5. 2019-10-10T22:07:36.158Z,1570745256.158 [BPC1](INFO): Received data from all battery sticks. 2019-10-10T22:07:51.120Z,1570745271.120 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T22:07:51.121Z,1570745271.121 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:07:51.121Z,1570745271.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:07:51.121Z,1570745271.121 [Default:CheckIn:D] Running Loop=1 2019-10-10T22:07:51.518Z,1570745271.518 [Default:CheckIn:D] Stopped 2019-10-10T22:07:51.518Z,1570745271.518 [Default:CheckIn:E] Running Loop=1 2019-10-10T22:07:51.929Z,1570745271.929 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.931523 min 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn:E] Stopped 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn] Stopped 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn](INFO): Running loop #7 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn] Running Loop=7 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T22:07:51.930Z,1570745271.930 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T22:07:53.517Z,1570745273.517 [NAL9602](DEBUG): Fix Requested 2019-10-10T22:07:53.911Z,1570745273.911 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220705.00,A,3648.16628,N,12147.28207,W,0.097,287.74,101019,,,A*7A 2019-10-10T22:07:53.914Z,1570745273.914 [NAL9602](INFO): GPS fix at 20191010T220705: (36.802771, -121.788034) 2019-10-10T22:07:53.990Z,1570745273.990 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T22:07:53.990Z,1570745273.990 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T22:07:56.029Z,1570745276.029 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T22:07:57.999Z,1570745277.999 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20191010T213714/Courier0018.lzma 2019-10-10T22:08:00.010Z,1570745280.010 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0018.lzma.bak 2019-10-10T22:08:00.010Z,1570745280.010 [DataOverHttps](INFO): SBD MOMSN=11872707 2019-10-10T22:08:19.737Z,1570745299.737 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0019.lzma 2019-10-10T22:08:21.788Z,1570745301.788 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0019.lzma.bak 2019-10-10T22:08:21.788Z,1570745301.788 [DataOverHttps](INFO): SBD MOMSN=11872710 2019-10-10T22:08:25.959Z,1570745305.959 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T22:08:25.960Z,1570745305.960 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T22:08:25.960Z,1570745305.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T22:09:26.119Z,1570745366.119 [NAL9602](INFO): SBD MO Status=2, MOMSN=3639, MT Status=2, MTMSN=0 2019-10-10T22:09:26.119Z,1570745366.119 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2019-10-10T22:10:21.472Z,1570745421.472 [NAL9602](INFO): SBD MO Status=0, MOMSN=3639, MT Status=0, MTMSN=0 2019-10-10T22:10:21.472Z,1570745421.472 [NAL9602](INFO): No messages in MT queue 2019-10-10T22:10:52.165Z,1570745452.165 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T22:13:26.585Z,1570745606.585 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T22:13:26.585Z,1570745606.585 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:13:26.585Z,1570745606.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:13:26.586Z,1570745606.586 [Default:CheckIn:D] Running Loop=1 2019-10-10T22:13:26.979Z,1570745606.979 [Default:CheckIn:D] Stopped 2019-10-10T22:13:26.979Z,1570745606.979 [Default:CheckIn:E] Running Loop=1 2019-10-10T22:13:27.401Z,1570745607.401 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.522538 min 2019-10-10T22:13:27.401Z,1570745607.401 [Default:CheckIn:E] Stopped 2019-10-10T22:13:27.401Z,1570745607.401 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T22:13:27.401Z,1570745607.401 [Default:CheckIn] Stopped 2019-10-10T22:13:27.401Z,1570745607.401 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:13:27.402Z,1570745607.402 [Default:CheckIn](INFO): Running loop #8 2019-10-10T22:13:27.402Z,1570745607.402 [Default:CheckIn] Running Loop=8 2019-10-10T22:13:27.402Z,1570745607.402 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T22:13:27.402Z,1570745607.402 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T22:13:28.977Z,1570745608.977 [NAL9602](DEBUG): Fix Requested 2019-10-10T22:13:29.368Z,1570745609.368 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221240.00,A,3648.16838,N,12147.28165,W,0.272,287.74,101019,,,A*7E 2019-10-10T22:13:29.370Z,1570745609.370 [NAL9602](INFO): GPS fix at 20191010T221240: (36.802806, -121.788027) 2019-10-10T22:13:29.393Z,1570745609.393 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T22:13:29.393Z,1570745609.393 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T22:13:30.989Z,1570745610.989 [NAL9602](ERROR): parseGSV uart error: serial buffer full 2019-10-10T22:13:41.004Z,1570745621.004 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20191010T213714/Courier0021.lzma 2019-10-10T22:13:43.010Z,1570745623.010 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0021.lzma.bak 2019-10-10T22:13:43.010Z,1570745623.010 [DataOverHttps](INFO): SBD MOMSN=11872720 2019-10-10T22:13:44.315Z,1570745624.315 [NAL9602](INFO): SBD MO Status=0, MOMSN=3640, MT Status=0, MTMSN=0 2019-10-10T22:13:44.315Z,1570745624.315 [NAL9602](INFO): No messages in MT queue 2019-10-10T22:14:02.644Z,1570745642.644 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0022.lzma 2019-10-10T22:14:04.650Z,1570745644.650 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0022.lzma.bak 2019-10-10T22:14:04.650Z,1570745644.650 [DataOverHttps](INFO): SBD MOMSN=11872723 2019-10-10T22:14:08.628Z,1570745648.628 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T22:14:08.629Z,1570745648.629 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T22:14:08.629Z,1570745648.629 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T22:14:15.022Z,1570745655.022 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T22:19:09.246Z,1570745949.246 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T22:19:09.246Z,1570745949.246 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:19:09.246Z,1570745949.246 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:19:09.247Z,1570745949.247 [Default:CheckIn:D] Running Loop=1 2019-10-10T22:19:09.637Z,1570745949.637 [Default:CheckIn:D] Stopped 2019-10-10T22:19:09.638Z,1570745949.638 [Default:CheckIn:E] Running Loop=1 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.233521 min 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn:E] Stopped 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn] Stopped 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn](INFO): Running loop #9 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn] Running Loop=9 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T22:19:10.034Z,1570745950.034 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T22:19:11.625Z,1570745951.625 [NAL9602](DEBUG): Fix Requested 2019-10-10T22:19:12.020Z,1570745952.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221823.00,A,3648.16462,N,12147.28276,W,0.078,352.08,101019,,,A*79 2019-10-10T22:19:12.033Z,1570745952.033 [NAL9602](INFO): GPS fix at 20191010T221823: (36.802744, -121.788046) 2019-10-10T22:19:12.061Z,1570745952.061 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T22:19:12.061Z,1570745952.061 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T22:19:21.848Z,1570745961.848 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T213714/Courier0024.lzma 2019-10-10T22:19:23.854Z,1570745963.854 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0024.lzma.bak 2019-10-10T22:19:23.854Z,1570745963.854 [DataOverHttps](INFO): SBD MOMSN=11872735 2019-10-10T22:19:24.135Z,1570745964.135 [NAL9602](INFO): SBD MO Status=0, MOMSN=3641, MT Status=0, MTMSN=0 2019-10-10T22:19:24.136Z,1570745964.136 [NAL9602](INFO): No messages in MT queue 2019-10-10T22:19:45.974Z,1570745985.974 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0025.lzma 2019-10-10T22:19:47.979Z,1570745987.979 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0025.lzma.bak 2019-10-10T22:19:47.979Z,1570745987.979 [DataOverHttps](INFO): SBD MOMSN=11872738 2019-10-10T22:19:52.054Z,1570745992.054 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T22:19:52.055Z,1570745992.055 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T22:19:52.055Z,1570745992.055 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T22:19:54.838Z,1570745994.838 [NAL9602](INFO): Not Powering down - fast GPS 2019-10-10T22:24:52.649Z,1570746292.649 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2019-10-10T22:24:52.649Z,1570746292.649 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:24:52.649Z,1570746292.649 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:24:52.650Z,1570746292.650 [Default:CheckIn:D] Running Loop=1 2019-10-10T22:24:53.042Z,1570746293.042 [Default:CheckIn:D] Stopped 2019-10-10T22:24:53.042Z,1570746293.042 [Default:CheckIn:E] Running Loop=1 2019-10-10T22:24:53.437Z,1570746293.437 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.956925 min 2019-10-10T22:24:53.437Z,1570746293.437 [Default:CheckIn:E] Stopped 2019-10-10T22:24:53.437Z,1570746293.437 [Default:CheckIn](INFO): Completed Default:CheckIn 2019-10-10T22:24:53.437Z,1570746293.437 [Default:CheckIn] Stopped 2019-10-10T22:24:53.437Z,1570746293.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:24:53.438Z,1570746293.438 [Default:CheckIn](INFO): Running loop #10 2019-10-10T22:24:53.438Z,1570746293.438 [Default:CheckIn] Running Loop=10 2019-10-10T22:24:53.438Z,1570746293.438 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2019-10-10T22:24:53.438Z,1570746293.438 [Default:CheckIn:Read_GPS] Running Loop=1 2019-10-10T22:24:55.045Z,1570746295.045 [NAL9602](DEBUG): Fix Requested 2019-10-10T22:24:55.434Z,1570746295.434 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222406.00,A,3648.16577,N,12147.28376,W,1.050,213.97,101019,,,A*7C 2019-10-10T22:24:55.436Z,1570746295.436 [NAL9602](INFO): GPS fix at 20191010T222406: (36.802763, -121.788063) 2019-10-10T22:24:55.479Z,1570746295.479 [Default:CheckIn:Read_GPS] Stopped 2019-10-10T22:24:55.479Z,1570746295.479 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-10-10T22:25:04.672Z,1570746304.672 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191010T213714/Courier0027.lzma 2019-10-10T22:25:06.678Z,1570746306.678 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Courier0027.lzma.bak 2019-10-10T22:25:06.678Z,1570746306.678 [DataOverHttps](INFO): SBD MOMSN=11872750 2019-10-10T22:25:10.382Z,1570746310.382 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-10T22:25:10.382Z,1570746310.382 [DropWeight] Hardware Fault, FailCount= 1 2019-10-10T22:25:10.382Z,1570746310.382 [DropWeight](ERROR): Hardware Fault 2019-10-10T22:25:10.479Z,1570746310.479 [CommandLine](FAULT): Scheduling is paused 2019-10-10T22:25:10.480Z,1570746310.480 [CBIT](INFO): Critical error at 20191010T222510 2019-10-10T22:25:10.482Z,1570746310.482 [CBIT](ERROR): Hardware Fault in component: DropWeight 2019-10-10T22:25:10.483Z,1570746310.483 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2019-10-10T22:25:10.839Z,1570746310.839 [CBIT](INFO): Critical error at 20191010T222510 2019-10-10T22:25:25.032Z,1570746325.032 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20191010T213714/Express0028.lzma 2019-10-10T22:25:27.038Z,1570746327.038 [DataOverHttps](INFO): Moved sent file to Logs/20191010T213714/Express0028.lzma.bak 2019-10-10T22:25:27.039Z,1570746327.039 [DataOverHttps](INFO): SBD MOMSN=11872753 2019-10-10T22:25:31.438Z,1570746331.438 [Default:CheckIn:Read_Iridium] Stopped 2019-10-10T22:25:31.439Z,1570746331.439 [Default:CheckIn:C.Wait] Running Loop=1 2019-10-10T22:25:31.439Z,1570746331.439 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-10-10T22:26:05.013Z,1570746365.013 [CommandLine](IMPORTANT): got command quit 2019-10-10T22:26:06.017Z,1570746366.017 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:06.017Z,1570746366.017 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.061Z,1570746366.061 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-10-10T22:26:06.061Z,1570746366.061 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.061Z,1570746366.061 [CommandLine](INFO): Join timeout helper Thread ID is 2234 2019-10-10T22:26:06.069Z,1570746366.069 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-10-10T22:26:06.069Z,1570746366.069 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.070Z,1570746366.070 [NavChartDb](INFO): Join timeout helper Thread ID is 2235 2019-10-10T22:26:06.337Z,1570746366.337 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:06.337Z,1570746366.337 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.357Z,1570746366.357 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-10-10T22:26:06.357Z,1570746366.357 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.357Z,1570746366.357 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2236 2019-10-10T22:26:06.501Z,1570746366.501 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:06.501Z,1570746366.501 [WetLabsBB2FL](INFO): Powering down 2019-10-10T22:26:06.502Z,1570746366.502 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.517Z,1570746366.517 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-10-10T22:26:06.517Z,1570746366.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:06.517Z,1570746366.517 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2237 2019-10-10T22:26:07.037Z,1570746367.037 [CTD_Seabird](INFO): Powering down 2019-10-10T22:26:07.049Z,1570746367.049 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:07.049Z,1570746367.049 [CTD_Seabird](INFO): Powering down 2019-10-10T22:26:07.065Z,1570746367.065 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.065Z,1570746367.065 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-10-10T22:26:07.065Z,1570746367.065 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.066Z,1570746367.066 [Radio_Surface](INFO): Join timeout helper Thread ID is 2238 2019-10-10T22:26:07.085Z,1570746367.085 [Radio_Surface](INFO): Powering down 2019-10-10T22:26:07.086Z,1570746367.086 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:07.086Z,1570746367.086 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.106Z,1570746367.106 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-10-10T22:26:07.106Z,1570746367.106 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.106Z,1570746367.106 [DataOverHttps](INFO): Join timeout helper Thread ID is 2239 2019-10-10T22:26:07.353Z,1570746367.353 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:07.354Z,1570746367.354 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.370Z,1570746367.370 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-10-10T22:26:07.370Z,1570746367.370 [logger ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.370Z,1570746367.370 [logger](INFO): Join timeout helper Thread ID is 2240 2019-10-10T22:26:07.370Z,1570746367.370 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:07.371Z,1570746367.371 [logger ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.372Z,1570746367.372 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-10-10T22:26:07.372Z,1570746367.372 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.372Z,1570746367.372 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-10-10T22:26:07.372Z,1570746367.372 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.373Z,1570746367.373 [controlThread](INFO): Join timeout helper Thread ID is 2241 2019-10-10T22:26:07.388Z,1570746367.388 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-10-10T22:26:07.388Z,1570746367.388 [controlThread](DEBUG): Uninitializing ControlThread 2019-10-10T22:26:07.388Z,1570746367.388 [AHRS_M2](INFO): Powering down 2019-10-10T22:26:07.461Z,1570746367.461 [NAL9602](INFO): Powering down 2019-10-10T22:26:07.464Z,1570746367.464 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-10-10T22:26:07.466Z,1570746367.466 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-10-10T22:26:07.466Z,1570746367.466 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-10-10T22:26:07.467Z,1570746367.467 [MissionManager](INFO): Uninitializing Mission Default 2019-10-10T22:26:07.467Z,1570746367.467 [Default] Stopped 2019-10-10T22:26:07.467Z,1570746367.467 [Default](DEBUG): Aggregate::uninitialize Default 2019-10-10T22:26:07.467Z,1570746367.467 [Default:B.GoToSurface] Stopped 2019-10-10T22:26:07.467Z,1570746367.467 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-10-10T22:26:07.467Z,1570746367.467 [Default:CheckIn] Stopped 2019-10-10T22:26:07.467Z,1570746367.467 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-10-10T22:26:07.467Z,1570746367.467 [Default:CheckIn:C.Wait] Stopped 2019-10-10T22:26:07.467Z,1570746367.467 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-10-10T22:26:07.470Z,1570746367.470 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-10-10T22:26:07.470Z,1570746367.470 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-10-10T22:26:07.470Z,1570746367.470 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-10-10T22:26:07.471Z,1570746367.471 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-10-10T22:26:07.471Z,1570746367.471 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-10-10T22:26:07.471Z,1570746367.471 [BuoyancyServo](INFO): Powering down 2019-10-10T22:26:07.485Z,1570746367.485 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-10-10T22:26:07.485Z,1570746367.485 [ElevatorServo](INFO): Powering down 2019-10-10T22:26:07.486Z,1570746367.486 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-10-10T22:26:07.486Z,1570746367.486 [MassServo](INFO): Powering down 2019-10-10T22:26:07.487Z,1570746367.487 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-10-10T22:26:07.487Z,1570746367.487 [RudderServo](INFO): Powering down 2019-10-10T22:26:07.488Z,1570746367.488 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-10-10T22:26:07.488Z,1570746367.488 [ThrusterServo](INFO): Powering down 2019-10-10T22:26:07.488Z,1570746367.488 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-10-10T22:26:07.489Z,1570746367.489 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-10-10T22:26:07.489Z,1570746367.489 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-10-10T22:26:07.489Z,1570746367.489 [CBIT](DEBUG): Powering off loads. 2019-10-10T22:26:07.501Z,1570746367.501 [CBIT](DEBUG): Disabling WDT. 2019-10-10T22:26:07.513Z,1570746367.513 [CBIT](DEBUG): Opening all GF detection circuits. 2019-10-10T22:26:07.514Z,1570746367.514 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.593Z,1570746367.593 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.599Z,1570746367.599 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.682Z,1570746367.682 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.770Z,1570746367.770 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.803Z,1570746367.803 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-10-10T22:26:07.894Z,1570746367.894 [logger ThreadHandler](INFO): Thread cancelled.