2018-10-11T16:41:27.831Z,1539276087.831 [CommandLine](IMPORTANT): got command restart logs
2018-10-11T16:43:18.261Z,1539276198.261 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-11T16:43:18.261Z,1539276198.261 [Default:CheckIn:C.Wait] Stopped
2018-10-11T16:43:18.261Z,1539276198.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-11T16:43:18.262Z,1539276198.262 [Default:CheckIn:D] Running Loop=1
2018-10-11T16:43:18.786Z,1539276198.786 [Default:CheckIn:D] Stopped
2018-10-11T16:43:18.787Z,1539276198.787 [Default:CheckIn:E] Running Loop=1
2018-10-11T16:43:19.057Z,1539276199.057 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 13.797128 min 
2018-10-11T16:43:19.057Z,1539276199.057 [Default:CheckIn:E] Stopped
2018-10-11T16:43:19.057Z,1539276199.057 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-11T16:43:19.057Z,1539276199.057 [Default:CheckIn] Stopped
2018-10-11T16:43:19.057Z,1539276199.057 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T16:43:19.058Z,1539276199.058 [Default:CheckIn](INFO): Running loop #3
2018-10-11T16:43:19.058Z,1539276199.058 [Default:CheckIn] Running Loop=3
2018-10-11T16:43:19.058Z,1539276199.058 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-11T16:43:19.058Z,1539276199.058 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-11T16:43:19.804Z,1539276199.804 [NAL9602](INFO): Powering up
2018-10-11T16:43:30.864Z,1539276210.864 [NAL9602](INFO): NAL9602 initialized
2018-10-11T16:43:42.866Z,1539276222.866 [NAL9602](INFO): SBD MO Status=0, MOMSN=10401, MT Status=0, MTMSN=0
2018-10-11T16:43:42.866Z,1539276222.866 [NAL9602](INFO): No messages in MT queue
2018-10-11T16:43:58.864Z,1539276238.864 [NAL9602](INFO): GPS fix at 20181011T164400: (36.806574, -121.797738)
2018-10-11T16:43:58.925Z,1539276238.925 [Default:CheckIn:Read_GPS] Stopped
2018-10-11T16:43:58.926Z,1539276238.926 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-11T16:44:04.013Z,1539276244.013 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181011T153600/Courier0012.lzma
2018-10-11T16:44:04.813Z,1539276244.813 [DataOverHttps](INFO): Moved sent file to Logs/20181011T153600/Courier0012.lzma.bak
2018-10-11T16:44:04.813Z,1539276244.813 [DataOverHttps](INFO): SBD MOMSN=8628062
2018-10-11T16:44:14.893Z,1539276254.893 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20181011T164127/Courier0000.lzma
2018-10-11T16:44:15.677Z,1539276255.677 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Courier0000.lzma.bak
2018-10-11T16:44:15.677Z,1539276255.677 [DataOverHttps](INFO): SBD MOMSN=8628064
2018-10-11T16:44:26.699Z,1539276266.699 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181011T164127/Courier0003.lzma
2018-10-11T16:44:27.497Z,1539276267.497 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Courier0003.lzma.bak
2018-10-11T16:44:27.497Z,1539276267.497 [DataOverHttps](INFO): SBD MOMSN=8628068
2018-10-11T16:44:37.048Z,1539276277.048 [NAL9602](INFO): Powering down
2018-10-11T16:44:37.137Z,1539276277.137 [DataOverHttps](INFO): Sending 199 bytes from file Logs/20181011T153600/Express0013.lzma
2018-10-11T16:44:37.937Z,1539276277.937 [DataOverHttps](INFO): Moved sent file to Logs/20181011T153600/Express0013.lzma.bak
2018-10-11T16:44:37.937Z,1539276277.937 [DataOverHttps](INFO): SBD MOMSN=8628070
2018-10-11T16:44:47.641Z,1539276287.641 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20181011T164127/Express0001.lzma
2018-10-11T16:44:48.431Z,1539276288.431 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Express0001.lzma.bak
2018-10-11T16:44:48.431Z,1539276288.431 [DataOverHttps](INFO): SBD MOMSN=8628073
2018-10-11T16:44:57.942Z,1539276297.942 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181011T164127/Express0004.lzma
2018-10-11T16:44:58.741Z,1539276298.741 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Express0004.lzma.bak
2018-10-11T16:44:58.741Z,1539276298.741 [DataOverHttps](INFO): SBD MOMSN=8628077
2018-10-11T16:44:59.341Z,1539276299.341 [Default:CheckIn:Read_Iridium] Stopped
2018-10-11T16:44:59.341Z,1539276299.341 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-11T16:44:59.341Z,1539276299.341 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-11T16:49:59.885Z,1539276599.885 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-11T16:49:59.885Z,1539276599.885 [Default:CheckIn:C.Wait] Stopped
2018-10-11T16:49:59.885Z,1539276599.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-11T16:49:59.886Z,1539276599.886 [Default:CheckIn:D] Running Loop=1
2018-10-11T16:50:00.269Z,1539276600.269 [Default:CheckIn:D] Stopped
2018-10-11T16:50:00.269Z,1539276600.269 [Default:CheckIn:E] Running Loop=1
2018-10-11T16:50:00.675Z,1539276600.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.488513 min 
2018-10-11T16:50:00.675Z,1539276600.675 [Default:CheckIn:E] Stopped
2018-10-11T16:50:00.675Z,1539276600.675 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn] Stopped
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn](INFO): Running loop #4
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn] Running Loop=4
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-11T16:50:00.676Z,1539276600.676 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-11T16:50:04.515Z,1539276604.515 [NAL9602](INFO): Powering up
2018-10-11T16:50:15.496Z,1539276615.496 [NAL9602](INFO): NAL9602 initialized
2018-10-11T16:50:30.697Z,1539276630.697 [NAL9602](INFO): SBD MO Status=0, MOMSN=10402, MT Status=0, MTMSN=0
2018-10-11T16:50:30.698Z,1539276630.698 [NAL9602](INFO): No messages in MT queue
2018-10-11T16:50:52.701Z,1539276652.701 [NAL9602](INFO): GPS fix at 20181011T165054: (36.802730, -121.786929)
2018-10-11T16:50:52.777Z,1539276652.777 [Default:CheckIn:Read_GPS] Stopped
2018-10-11T16:50:52.778Z,1539276652.778 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-11T16:50:57.725Z,1539276657.725 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20181011T164127/Courier0006.lzma
2018-10-11T16:50:58.508Z,1539276658.508 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Courier0006.lzma.bak
2018-10-11T16:50:58.509Z,1539276658.509 [DataOverHttps](INFO): SBD MOMSN=8628136
2018-10-11T16:51:08.252Z,1539276668.252 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181011T164127/Express0007.lzma
2018-10-11T16:51:09.025Z,1539276669.025 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Express0007.lzma.bak
2018-10-11T16:51:09.025Z,1539276669.025 [DataOverHttps](INFO): SBD MOMSN=8628158
2018-10-11T16:51:09.572Z,1539276669.572 [Default:CheckIn:Read_Iridium] Stopped
2018-10-11T16:51:09.572Z,1539276669.572 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-11T16:51:09.572Z,1539276669.572 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-11T16:51:28.040Z,1539276688.040 [NAL9602](INFO): Powering down
2018-10-11T16:56:10.197Z,1539276970.197 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-11T16:56:10.198Z,1539276970.198 [Default:CheckIn:C.Wait] Stopped
2018-10-11T16:56:10.198Z,1539276970.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-11T16:56:10.202Z,1539276970.202 [Default:CheckIn:D] Running Loop=1
2018-10-11T16:56:10.593Z,1539276970.593 [Default:CheckIn:D] Stopped
2018-10-11T16:56:10.593Z,1539276970.593 [Default:CheckIn:E] Running Loop=1
2018-10-11T16:56:11.082Z,1539276971.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.660583 min 
2018-10-11T16:56:11.082Z,1539276971.082 [Default:CheckIn:E] Stopped
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn] Stopped
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn](INFO): Running loop #5
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn] Running Loop=5
2018-10-11T16:56:11.083Z,1539276971.083 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-11T16:56:11.084Z,1539276971.084 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-11T16:56:14.672Z,1539276974.672 [NAL9602](INFO): Powering up
2018-10-11T16:56:25.820Z,1539276985.820 [NAL9602](INFO): NAL9602 initialized
2018-10-11T16:56:41.817Z,1539277001.817 [NAL9602](INFO): SBD MO Status=0, MOMSN=10403, MT Status=0, MTMSN=0
2018-10-11T16:56:41.817Z,1539277001.817 [NAL9602](INFO): No messages in MT queue
2018-10-11T16:56:57.820Z,1539277017.820 [NAL9602](INFO): GPS fix at 20181011T165659: (36.802817, -121.786919)
2018-10-11T16:56:57.869Z,1539277017.869 [Default:CheckIn:Read_GPS] Stopped
2018-10-11T16:56:57.869Z,1539277017.869 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-11T16:57:02.855Z,1539277022.855 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20181011T164127/Courier0009.lzma
2018-10-11T16:57:03.657Z,1539277023.657 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Courier0009.lzma.bak
2018-10-11T16:57:03.657Z,1539277023.657 [DataOverHttps](INFO): SBD MOMSN=8628163
2018-10-11T16:57:13.164Z,1539277033.164 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20181011T164127/Express0010.lzma
2018-10-11T16:57:13.965Z,1539277033.965 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Express0010.lzma.bak
2018-10-11T16:57:13.965Z,1539277033.965 [DataOverHttps](INFO): SBD MOMSN=8628166
2018-10-11T16:57:14.634Z,1539277034.634 [Default:CheckIn:Read_Iridium] Stopped
2018-10-11T16:57:14.634Z,1539277034.634 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-11T16:57:14.634Z,1539277034.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-11T16:57:33.232Z,1539277053.232 [NAL9602](INFO): Powering down
2018-10-11T17:02:14.926Z,1539277334.926 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-11T17:02:14.927Z,1539277334.927 [Default:CheckIn:C.Wait] Stopped
2018-10-11T17:02:14.927Z,1539277334.927 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-11T17:02:14.928Z,1539277334.928 [Default:CheckIn:D] Running Loop=1
2018-10-11T17:02:15.302Z,1539277335.302 [Default:CheckIn:D] Stopped
2018-10-11T17:02:15.302Z,1539277335.302 [Default:CheckIn:E] Running Loop=1
2018-10-11T17:02:15.727Z,1539277335.727 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.739065 min 
2018-10-11T17:02:15.727Z,1539277335.727 [Default:CheckIn:E] Stopped
2018-10-11T17:02:15.727Z,1539277335.727 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-11T17:02:15.727Z,1539277335.727 [Default:CheckIn] Stopped
2018-10-11T17:02:15.727Z,1539277335.727 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T17:02:15.728Z,1539277335.728 [Default:CheckIn](INFO): Running loop #6
2018-10-11T17:02:15.728Z,1539277335.728 [Default:CheckIn] Running Loop=6
2018-10-11T17:02:15.728Z,1539277335.728 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-11T17:02:15.728Z,1539277335.728 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-11T17:02:20.045Z,1539277340.045 [NAL9602](INFO): Powering up
2018-10-11T17:02:21.362Z,1539277341.362 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-10-11T17:02:21.363Z,1539277341.363 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-10-11T17:02:21.363Z,1539277341.363 [Rowe_600LCM](ERROR): Communications Fault
2018-10-11T17:02:21.742Z,1539277341.742 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-10-11T17:02:21.766Z,1539277341.766 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-10-11T17:02:21.988Z,1539277341.988 [Rowe_600LCM](INFO): Powering down
2018-10-11T17:02:22.795Z,1539277342.795 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-10-11T17:02:22.795Z,1539277342.795 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-10-11T17:02:22.795Z,1539277342.795 [Rowe_600LCM](ERROR): Hardware Fault
2018-10-11T17:02:23.331Z,1539277343.331 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-10-11T17:02:23.331Z,1539277343.331 [Rowe_600LCM] No Fault, FailCount= 1
2018-10-11T17:02:23.602Z,1539277343.602 [Rowe_600LCM](INFO): Initializing
2018-10-11T17:02:23.603Z,1539277343.603 [Rowe_600LCM](INFO): Checking LCM
2018-10-11T17:02:23.603Z,1539277343.603 [Rowe_600LCM](INFO): LCM OK
2018-10-11T17:02:23.603Z,1539277343.603 [Rowe_600LCM](INFO): Powering up
2018-10-11T17:02:27.833Z,1539277347.833 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-10-11T17:02:27.898Z,1539277347.898 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-10-11T17:02:27.900Z,1539277347.900 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-10-11T17:02:27.901Z,1539277347.901 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-10-11T17:02:27.902Z,1539277347.902 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-10-11T17:02:31.232Z,1539277351.232 [NAL9602](INFO): NAL9602 initialized
2018-10-11T17:02:47.233Z,1539277367.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=10404, MT Status=0, MTMSN=0
2018-10-11T17:02:47.233Z,1539277367.233 [NAL9602](INFO): No messages in MT queue
2018-10-11T17:07:16.090Z,1539277636.090 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-10-11T17:02:15.7Z
2018-10-11T17:07:16.090Z,1539277636.090 [Default:CheckIn:Read_GPS] Stopped
2018-10-11T17:07:16.090Z,1539277636.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-10-11T17:07:20.370Z,1539277640.370 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20181011T164127/Courier0012.lzma
2018-10-11T17:07:21.165Z,1539277641.165 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Courier0012.lzma.bak
2018-10-11T17:07:21.165Z,1539277641.165 [DataOverHttps](INFO): SBD MOMSN=8628172
2018-10-11T17:07:31.110Z,1539277651.110 [DataOverHttps](INFO): Sending 257 bytes from file Logs/20181011T164127/Express0013.lzma
2018-10-11T17:07:31.897Z,1539277651.897 [DataOverHttps](INFO): Moved sent file to Logs/20181011T164127/Express0013.lzma.bak
2018-10-11T17:07:31.897Z,1539277651.897 [DataOverHttps](INFO): SBD MOMSN=8628174
2018-10-11T17:07:32.447Z,1539277652.447 [Default:CheckIn:Read_Iridium] Stopped
2018-10-11T17:07:32.447Z,1539277652.447 [Default:CheckIn:C.Wait] Running Loop=1
2018-10-11T17:07:32.448Z,1539277652.448 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-10-11T17:07:50.632Z,1539277670.632 [NAL9602](INFO): Powering down
2018-10-11T17:12:32.809Z,1539277952.809 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-10-11T17:12:32.809Z,1539277952.809 [Default:CheckIn:C.Wait] Stopped
2018-10-11T17:12:32.809Z,1539277952.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-10-11T17:12:32.809Z,1539277952.809 [Default:CheckIn:D] Running Loop=1
2018-10-11T17:12:33.221Z,1539277953.221 [Default:CheckIn:D] Stopped
2018-10-11T17:12:33.221Z,1539277953.221 [Default:CheckIn:E] Running Loop=1
2018-10-11T17:12:33.621Z,1539277953.621 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.037712 min 
2018-10-11T17:12:33.622Z,1539277953.622 [Default:CheckIn:E] Stopped
2018-10-11T17:12:33.622Z,1539277953.622 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-10-11T17:12:33.622Z,1539277953.622 [Default:CheckIn] Stopped
2018-10-11T17:12:33.626Z,1539277953.626 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T17:12:33.627Z,1539277953.627 [Default:CheckIn](INFO): Running loop #7
2018-10-11T17:12:33.627Z,1539277953.627 [Default:CheckIn] Running Loop=7
2018-10-11T17:12:33.627Z,1539277953.627 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-10-11T17:12:33.627Z,1539277953.627 [Default:CheckIn:Read_GPS] Running Loop=1
2018-10-11T17:12:37.421Z,1539277957.421 [NAL9602](INFO): Powering up
2018-10-11T17:12:48.360Z,1539277968.360 [NAL9602](INFO): NAL9602 initialized
2018-10-11T17:12:49.164Z,1539277969.164 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-10-11T17:12:49.164Z,1539277969.164 [NAL9602] Data Fault, FailCount= 1
2018-10-11T17:12:49.164Z,1539277969.164 [NAL9602](ERROR): Data Fault
2018-10-11T17:12:49.239Z,1539277969.239 [CBIT](ERROR): Data Fault in component: NAL9602
2018-10-11T17:12:49.556Z,1539277969.556 [NAL9602](INFO): Powering down
2018-10-11T17:12:50.416Z,1539277970.416 [CBIT](INFO): Clearing failed state for component NAL9602
2018-10-11T17:12:50.416Z,1539277970.416 [NAL9602] No Fault, FailCount= 1
2018-10-11T17:13:19.956Z,1539277999.956 [NAL9602](INFO): Powering up NAL9602
2018-10-11T17:13:31.156Z,1539278011.156 [NAL9602](INFO): NAL9602 initialized
2018-10-11T17:13:40.128Z,1539278020.128 [CommandLine](IMPORTANT): got command quit
2018-10-11T17:13:41.138Z,1539278021.138 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:41.138Z,1539278021.138 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.202Z,1539278021.202 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-10-11T17:13:41.202Z,1539278021.202 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.203Z,1539278021.203 [CommandLine](INFO): Join timeout helper Thread ID is 9495
2018-10-11T17:13:41.204Z,1539278021.204 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-10-11T17:13:41.204Z,1539278021.204 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.204Z,1539278021.204 [NavChartDb](INFO): Join timeout helper Thread ID is 9496
2018-10-11T17:13:41.246Z,1539278021.246 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:41.247Z,1539278021.247 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.267Z,1539278021.267 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-10-11T17:13:41.267Z,1539278021.267 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.267Z,1539278021.267 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9497
2018-10-11T17:13:41.387Z,1539278021.387 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:41.387Z,1539278021.387 [WetLabsBB2FL](INFO): Powering down
2018-10-11T17:13:41.388Z,1539278021.388 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.390Z,1539278021.390 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2018-10-11T17:13:41.390Z,1539278021.390 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.391Z,1539278021.391 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 9498
2018-10-11T17:13:41.683Z,1539278021.683 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:41.683Z,1539278021.683 [CTD_NeilBrown](INFO): Powering down
2018-10-11T17:13:41.683Z,1539278021.683 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.694Z,1539278021.694 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-10-11T17:13:41.694Z,1539278021.694 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:41.695Z,1539278021.695 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 9499
2018-10-11T17:13:42.303Z,1539278022.303 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:42.303Z,1539278022.303 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-10-11T17:13:42.471Z,1539278022.471 [Rowe_600LCM](INFO): Powering down
2018-10-11T17:13:42.473Z,1539278022.473 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.476Z,1539278022.476 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-10-11T17:13:42.476Z,1539278022.476 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.477Z,1539278022.477 [Radio_Surface](INFO): Join timeout helper Thread ID is 9501
2018-10-11T17:13:42.667Z,1539278022.667 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:42.668Z,1539278022.668 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.683Z,1539278022.683 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-10-11T17:13:42.684Z,1539278022.684 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.684Z,1539278022.684 [logger](INFO): Join timeout helper Thread ID is 9502
2018-10-11T17:13:42.686Z,1539278022.686 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:42.687Z,1539278022.687 [logger ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.688Z,1539278022.688 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-10-11T17:13:42.688Z,1539278022.688 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.688Z,1539278022.688 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-10-11T17:13:42.689Z,1539278022.689 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.689Z,1539278022.689 [controlThread](INFO): Join timeout helper Thread ID is 9503
2018-10-11T17:13:42.713Z,1539278022.713 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-10-11T17:13:42.713Z,1539278022.713 [controlThread](DEBUG): Uninitializing ControlThread
2018-10-11T17:13:42.714Z,1539278022.714 [NAL9602](INFO): Powering down
2018-10-11T17:13:42.716Z,1539278022.716 [PNI_TCM](INFO): Powering down
2018-10-11T17:13:42.803Z,1539278022.803 [Aanderaa_O2](INFO): Powering down
2018-10-11T17:13:42.804Z,1539278022.804 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-10-11T17:13:42.805Z,1539278022.805 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-10-11T17:13:42.806Z,1539278022.806 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-10-11T17:13:42.806Z,1539278022.806 [MissionManager](INFO): Uninitializing Mission Default
2018-10-11T17:13:42.807Z,1539278022.807 [Default] Stopped
2018-10-11T17:13:42.807Z,1539278022.807 [Default](DEBUG): Aggregate::uninitialize Default
2018-10-11T17:13:42.807Z,1539278022.807 [Default:B.GoToSurface] Stopped
2018-10-11T17:13:42.807Z,1539278022.807 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-10-11T17:13:42.807Z,1539278022.807 [Default:CheckIn] Stopped
2018-10-11T17:13:42.807Z,1539278022.807 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-10-11T17:13:42.807Z,1539278022.807 [Default:CheckIn:Read_GPS] Stopped
2018-10-11T17:13:42.811Z,1539278022.811 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-10-11T17:13:42.811Z,1539278022.811 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-10-11T17:13:42.811Z,1539278022.811 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-10-11T17:13:42.812Z,1539278022.812 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-10-11T17:13:42.812Z,1539278022.812 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-10-11T17:13:42.812Z,1539278022.812 [BuoyancyServo](INFO): Powering down
2018-10-11T17:13:42.826Z,1539278022.826 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-10-11T17:13:42.827Z,1539278022.827 [ElevatorServo](INFO): Powering down
2018-10-11T17:13:42.828Z,1539278022.828 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-10-11T17:13:42.828Z,1539278022.828 [MassServo](INFO): Powering down
2018-10-11T17:13:42.829Z,1539278022.829 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-10-11T17:13:42.829Z,1539278022.829 [RudderServo](INFO): Powering down
2018-10-11T17:13:42.830Z,1539278022.830 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-10-11T17:13:42.830Z,1539278022.830 [ThrusterServo](INFO): Powering down
2018-10-11T17:13:42.831Z,1539278022.831 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-10-11T17:13:42.831Z,1539278022.831 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-10-11T17:13:42.832Z,1539278022.832 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-10-11T17:13:42.833Z,1539278022.833 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.899Z,1539278022.899 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.906Z,1539278022.906 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.944Z,1539278022.944 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.948Z,1539278022.948 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:42.975Z,1539278022.975 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-10-11T17:13:43.069Z,1539278023.069 [logger ThreadHandler](INFO): Thread cancelled.