2018-06-01T15:43:59.863Z,1527867839.863 [DataOverHttps](INFO): Received command:restart logs 2018-06-01T15:43:59.870Z,1527867839.870 [CommandLine](IMPORTANT): got command restart logs 2018-06-01T15:44:00.633Z,1527867840.633 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T15:44:00.634Z,1527867840.634 [BPC1] Communications Fault, FailCount= 9 2018-06-01T15:44:00.634Z,1527867840.634 [BPC1](ERROR): Communications Fault 2018-06-01T15:44:00.683Z,1527867840.683 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T15:44:01.578Z,1527867841.578 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T15:44:01.578Z,1527867841.578 [BPC1] No Fault, FailCount= 9 2018-06-01T15:44:07.421Z,1527867847.421 [DataOverHttps](INFO): Sending 340 bytes from file Logs/20180601T120406/Express0053.lzma 2018-06-01T15:44:08.208Z,1527867848.208 [DataOverHttps](INFO): Moved sent file to Logs/20180601T120406/Express0053.lzma.bak 2018-06-01T15:44:08.208Z,1527867848.208 [DataOverHttps](INFO): SBD MOMSN=8302999 2018-06-01T15:44:17.177Z,1527867857.177 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T120406/Courier0055.lzma 2018-06-01T15:44:17.969Z,1527867857.969 [DataOverHttps](INFO): Moved sent file to Logs/20180601T120406/Courier0055.lzma.bak 2018-06-01T15:44:17.969Z,1527867857.969 [DataOverHttps](INFO): SBD MOMSN=8303010 2018-06-01T15:44:27.217Z,1527867867.217 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Courier0000.lzma 2018-06-01T15:44:28.016Z,1527867868.016 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0000.lzma.bak 2018-06-01T15:44:28.016Z,1527867868.016 [DataOverHttps](INFO): SBD MOMSN=8303012 2018-06-01T15:44:32.459Z,1527867872.459 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:44:32.459Z,1527867872.459 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 73 2018-06-01T15:44:32.459Z,1527867872.459 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:44:32.472Z,1527867872.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:44:32.613Z,1527867872.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:44:32.613Z,1527867872.613 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 73 2018-06-01T15:44:32.966Z,1527867872.966 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:44:32.967Z,1527867872.967 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:44:32.967Z,1527867872.967 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T15:44:37.326Z,1527867877.326 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20180601T120406/Express0056.lzma 2018-06-01T15:44:38.124Z,1527867878.124 [DataOverHttps](INFO): Moved sent file to Logs/20180601T120406/Express0056.lzma.bak 2018-06-01T15:44:38.125Z,1527867878.125 [DataOverHttps](INFO): SBD MOMSN=8303015 2018-06-01T15:44:47.401Z,1527867887.401 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Express0001.lzma 2018-06-01T15:44:48.188Z,1527867888.188 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0001.lzma.bak 2018-06-01T15:44:48.188Z,1527867888.188 [DataOverHttps](INFO): SBD MOMSN=8303018 2018-06-01T15:44:49.432Z,1527867889.432 [Default:CheckIn:Read_Iridium] Stopped 2018-06-01T15:44:49.432Z,1527867889.432 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-01T15:44:49.432Z,1527867889.432 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-01T15:45:20.710Z,1527867920.710 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-01T15:45:20.710Z,1527867920.710 [NAL9602] No Fault, FailCount= 5 2018-06-01T15:45:21.054Z,1527867921.054 [NAL9602](INFO): Powering up NAL9602 2018-06-01T15:45:31.854Z,1527867931.854 [NAL9602](INFO): NAL9602 initialized 2018-06-01T15:46:27.546Z,1527867987.546 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T15:46:27.546Z,1527867987.546 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-01T15:46:27.793Z,1527867987.793 [Rowe_600LCM](INFO): Initializing 2018-06-01T15:46:27.793Z,1527867987.793 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T15:46:27.794Z,1527867987.794 [Rowe_600LCM](INFO): LCM OK 2018-06-01T15:46:27.794Z,1527867987.794 [Rowe_600LCM](INFO): Powering up 2018-06-01T15:46:32.031Z,1527867992.031 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T15:46:32.099Z,1527867992.099 [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-06-01T15:46:32.100Z,1527867992.100 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T15:46:32.101Z,1527867992.101 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T15:46:32.102Z,1527867992.102 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T15:46:50.002Z,1527868010.002 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:46:50.797Z,1527868010.797 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:47:01.734Z,1527868021.734 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:47:02.529Z,1527868022.529 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:47:33.056Z,1527868053.056 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:47:33.056Z,1527868053.056 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 74 2018-06-01T15:47:33.057Z,1527868053.057 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:47:33.070Z,1527868053.070 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:47:33.565Z,1527868053.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:47:33.565Z,1527868053.565 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 74 2018-06-01T15:47:33.867Z,1527868053.867 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:47:33.868Z,1527868053.868 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:47:33.868Z,1527868053.868 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T15:48:28.565Z,1527868108.565 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T15:48:28.565Z,1527868108.565 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-01T15:48:28.565Z,1527868108.565 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T15:48:28.686Z,1527868108.686 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T15:48:28.969Z,1527868108.969 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T15:48:29.176Z,1527868109.176 [Rowe_600LCM](INFO): Powering down 2018-06-01T15:48:30.646Z,1527868110.646 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T15:48:30.646Z,1527868110.646 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-01T15:48:30.793Z,1527868110.793 [Rowe_600LCM](INFO): Initializing 2018-06-01T15:48:30.793Z,1527868110.793 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T15:48:30.794Z,1527868110.794 [Rowe_600LCM](INFO): LCM OK 2018-06-01T15:48:30.794Z,1527868110.794 [Rowe_600LCM](INFO): Powering up 2018-06-01T15:48:34.919Z,1527868114.919 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T15:48:35.032Z,1527868115.032 [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-06-01T15:48:35.033Z,1527868115.033 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T15:48:35.034Z,1527868115.034 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T15:48:35.034Z,1527868115.034 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T15:48:50.902Z,1527868130.902 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:48:51.697Z,1527868131.697 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:49:49.782Z,1527868189.782 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-01T15:49:49.782Z,1527868189.782 [Default:CheckIn:C.Wait] Stopped 2018-06-01T15:49:49.783Z,1527868189.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-01T15:49:49.783Z,1527868189.783 [Default:CheckIn:D] Running Loop=1 2018-06-01T15:49:50.214Z,1527868190.214 [Default:CheckIn:D] Stopped 2018-06-01T15:49:50.214Z,1527868190.214 [Default:CheckIn:E] Running Loop=1 2018-06-01T15:49:50.581Z,1527868190.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.615649 min 2018-06-01T15:49:50.581Z,1527868190.581 [Default:CheckIn:E] Stopped 2018-06-01T15:49:50.581Z,1527868190.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-01T15:49:50.581Z,1527868190.581 [Default:CheckIn] Stopped 2018-06-01T15:49:50.581Z,1527868190.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-01T15:49:50.582Z,1527868190.582 [Default:CheckIn](INFO): Running loop #8 2018-06-01T15:49:50.582Z,1527868190.582 [Default:CheckIn] Running Loop=8 2018-06-01T15:49:50.582Z,1527868190.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-01T15:49:50.582Z,1527868190.582 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-01T15:50:07.743Z,1527868207.743 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T15:50:07.744Z,1527868207.744 [BPC1] Communications Fault, FailCount= 10 2018-06-01T15:50:07.744Z,1527868207.744 [BPC1](ERROR): Communications Fault 2018-06-01T15:50:07.767Z,1527868207.767 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T15:50:07.768Z,1527868207.768 [CBIT](CRITICAL): Communications Fault in component: BPC1 2018-06-01T15:50:08.242Z,1527868208.242 [CBIT](INFO): Critical error at 20180601T155007 2018-06-01T15:50:30.963Z,1527868230.963 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T15:50:30.963Z,1527868230.963 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-01T15:50:30.963Z,1527868230.963 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T15:50:31.032Z,1527868231.032 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T15:50:31.365Z,1527868231.365 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T15:50:31.491Z,1527868231.491 [Rowe_600LCM](INFO): Powering down 2018-06-01T15:50:32.972Z,1527868232.972 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T15:50:32.972Z,1527868232.972 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-01T15:50:33.105Z,1527868233.105 [Rowe_600LCM](INFO): Initializing 2018-06-01T15:50:33.105Z,1527868233.105 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T15:50:33.106Z,1527868233.106 [Rowe_600LCM](INFO): LCM OK 2018-06-01T15:50:33.106Z,1527868233.106 [Rowe_600LCM](INFO): Powering up 2018-06-01T15:50:34.139Z,1527868234.139 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:50:34.139Z,1527868234.139 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 75 2018-06-01T15:50:34.139Z,1527868234.139 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:50:34.173Z,1527868234.173 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:50:34.587Z,1527868234.587 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:50:34.588Z,1527868234.588 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 75 2018-06-01T15:50:34.943Z,1527868234.943 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:50:34.944Z,1527868234.944 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:50:34.944Z,1527868234.944 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T15:50:37.238Z,1527868237.238 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T15:50:37.344Z,1527868237.344 [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-06-01T15:50:37.345Z,1527868237.345 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T15:50:37.345Z,1527868237.345 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T15:50:37.346Z,1527868237.346 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T15:51:21.134Z,1527868281.134 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:51:21.930Z,1527868281.930 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:52:33.154Z,1527868353.154 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T15:52:33.154Z,1527868353.154 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-01T15:52:33.154Z,1527868353.154 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T15:52:33.209Z,1527868353.209 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T15:52:33.557Z,1527868353.557 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T15:52:33.653Z,1527868353.653 [Rowe_600LCM](INFO): Powering down 2018-06-01T15:52:35.126Z,1527868355.126 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T15:52:35.126Z,1527868355.126 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-01T15:52:35.269Z,1527868355.269 [Rowe_600LCM](INFO): Initializing 2018-06-01T15:52:35.269Z,1527868355.269 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T15:52:35.270Z,1527868355.270 [Rowe_600LCM](INFO): LCM OK 2018-06-01T15:52:35.270Z,1527868355.270 [Rowe_600LCM](INFO): Powering up 2018-06-01T15:52:39.393Z,1527868359.393 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T15:52:39.516Z,1527868359.516 [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-06-01T15:52:39.517Z,1527868359.517 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T15:52:39.517Z,1527868359.517 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T15:52:39.518Z,1527868359.518 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T15:53:35.079Z,1527868415.079 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:53:35.079Z,1527868415.079 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 76 2018-06-01T15:53:35.080Z,1527868415.080 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:53:35.094Z,1527868415.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:53:35.490Z,1527868415.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:53:35.490Z,1527868415.490 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 76 2018-06-01T15:53:35.880Z,1527868415.880 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:53:35.880Z,1527868415.880 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:53:35.880Z,1527868415.880 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T15:54:35.322Z,1527868475.322 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T15:54:35.322Z,1527868475.322 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-01T15:54:35.322Z,1527868475.322 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T15:54:35.567Z,1527868475.567 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T15:54:35.725Z,1527868475.725 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T15:54:35.818Z,1527868475.818 [Rowe_600LCM](INFO): Powering down 2018-06-01T15:54:37.199Z,1527868477.199 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T15:54:37.199Z,1527868477.199 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-01T15:54:37.433Z,1527868477.433 [Rowe_600LCM](INFO): Initializing 2018-06-01T15:54:37.433Z,1527868477.433 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T15:54:37.434Z,1527868477.434 [Rowe_600LCM](INFO): LCM OK 2018-06-01T15:54:37.434Z,1527868477.434 [Rowe_600LCM](INFO): Powering up 2018-06-01T15:54:41.678Z,1527868481.678 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T15:54:41.740Z,1527868481.740 [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-06-01T15:54:41.741Z,1527868481.741 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T15:54:41.741Z,1527868481.741 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T15:54:41.742Z,1527868481.742 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T15:54:50.818Z,1527868490.818 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-01T15:49:50.6Z 2018-06-01T15:54:50.818Z,1527868490.818 [Default:CheckIn:Read_GPS] Stopped 2018-06-01T15:54:50.819Z,1527868490.819 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-01T15:54:56.376Z,1527868496.376 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20180601T154359/Courier0003.lzma 2018-06-01T15:54:57.167Z,1527868497.167 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0003.lzma.bak 2018-06-01T15:54:57.167Z,1527868497.167 [DataOverHttps](INFO): SBD MOMSN=8303027 2018-06-01T15:55:07.254Z,1527868507.254 [DataOverHttps](INFO): Sending 349 bytes from file Logs/20180601T154359/Express0004.lzma 2018-06-01T15:55:08.048Z,1527868508.048 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0004.lzma.bak 2018-06-01T15:55:08.048Z,1527868508.048 [DataOverHttps](INFO): SBD MOMSN=8303030 2018-06-01T15:55:08.134Z,1527868508.134 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T15:55:08.134Z,1527868508.134 [BPC1] No Fault, FailCount= 10 2018-06-01T15:55:09.531Z,1527868509.531 [Default:CheckIn:Read_Iridium] Stopped 2018-06-01T15:55:09.531Z,1527868509.531 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-01T15:55:09.531Z,1527868509.531 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-01T15:55:32.954Z,1527868532.954 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-01T15:55:32.955Z,1527868532.955 [NAL9602] Data Fault, FailCount= 1 2018-06-01T15:55:32.955Z,1527868532.955 [NAL9602](ERROR): Data Fault 2018-06-01T15:55:33.046Z,1527868533.046 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-01T15:55:33.354Z,1527868533.354 [NAL9602](INFO): Powering down 2018-06-01T15:55:34.213Z,1527868534.213 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-01T15:55:34.213Z,1527868534.213 [NAL9602] No Fault, FailCount= 1 2018-06-01T15:55:39.526Z,1527868539.526 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:55:40.321Z,1527868540.321 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T15:56:03.758Z,1527868563.758 [NAL9602](INFO): Powering up NAL9602 2018-06-01T15:56:14.989Z,1527868574.989 [NAL9602](INFO): NAL9602 initialized 2018-06-01T15:56:36.208Z,1527868596.208 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:56:36.208Z,1527868596.208 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 77 2018-06-01T15:56:36.208Z,1527868596.208 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:56:36.241Z,1527868596.241 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:56:36.641Z,1527868596.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:56:36.641Z,1527868596.641 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 77 2018-06-01T15:56:37.012Z,1527868597.012 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:56:37.012Z,1527868597.012 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:56:37.013Z,1527868597.013 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T15:56:37.508Z,1527868597.508 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T15:56:37.508Z,1527868597.508 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-01T15:56:37.508Z,1527868597.508 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T15:56:37.538Z,1527868597.538 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T15:56:37.539Z,1527868597.539 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-06-01T15:56:37.913Z,1527868597.913 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T15:56:38.007Z,1527868598.007 [Rowe_600LCM](INFO): Powering down 2018-06-01T15:59:37.378Z,1527868777.378 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T15:59:37.378Z,1527868777.378 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 78 2018-06-01T15:59:37.379Z,1527868777.379 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T15:59:37.392Z,1527868777.392 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T15:59:37.789Z,1527868777.789 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T15:59:37.790Z,1527868777.790 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 78 2018-06-01T15:59:38.178Z,1527868778.178 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T15:59:38.179Z,1527868778.179 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T15:59:38.179Z,1527868778.179 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:00:09.789Z,1527868809.789 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-01T16:00:09.790Z,1527868809.790 [Default:CheckIn:C.Wait] Stopped 2018-06-01T16:00:09.790Z,1527868809.790 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-01T16:00:09.790Z,1527868809.790 [Default:CheckIn:D] Running Loop=1 2018-06-01T16:00:10.180Z,1527868810.180 [Default:CheckIn:D] Stopped 2018-06-01T16:00:10.180Z,1527868810.180 [Default:CheckIn:E] Running Loop=1 2018-06-01T16:00:10.592Z,1527868810.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.948405 min 2018-06-01T16:00:10.592Z,1527868810.592 [Default:CheckIn:E] Stopped 2018-06-01T16:00:10.592Z,1527868810.592 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-01T16:00:10.592Z,1527868810.592 [Default:CheckIn] Stopped 2018-06-01T16:00:10.592Z,1527868810.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-01T16:00:10.593Z,1527868810.593 [Default:CheckIn](INFO): Running loop #9 2018-06-01T16:00:10.593Z,1527868810.593 [Default:CheckIn] Running Loop=9 2018-06-01T16:00:10.593Z,1527868810.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-01T16:00:10.593Z,1527868810.593 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-01T16:02:21.931Z,1527868941.931 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T16:02:21.931Z,1527868941.931 [BPC1] Communications Fault, FailCount= 1 2018-06-01T16:02:21.931Z,1527868941.931 [BPC1](ERROR): Communications Fault 2018-06-01T16:02:21.988Z,1527868941.988 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T16:02:23.142Z,1527868943.142 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T16:02:23.142Z,1527868943.142 [BPC1] No Fault, FailCount= 1 2018-06-01T16:02:38.335Z,1527868958.335 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:02:38.336Z,1527868958.336 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 79 2018-06-01T16:02:38.336Z,1527868958.336 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:02:38.349Z,1527868958.349 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:02:38.744Z,1527868958.744 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:02:38.744Z,1527868958.744 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 79 2018-06-01T16:02:39.133Z,1527868959.133 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:02:39.133Z,1527868959.133 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:02:39.134Z,1527868959.134 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:05:10.726Z,1527869110.726 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-01T16:00:10.6Z 2018-06-01T16:05:10.726Z,1527869110.726 [Default:CheckIn:Read_GPS] Stopped 2018-06-01T16:05:10.726Z,1527869110.726 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-01T16:05:14.788Z,1527869114.788 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Courier0006.lzma 2018-06-01T16:05:15.589Z,1527869115.589 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0006.lzma.bak 2018-06-01T16:05:15.589Z,1527869115.589 [DataOverHttps](INFO): SBD MOMSN=8303048 2018-06-01T16:05:25.693Z,1527869125.693 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20180601T154359/Express0007.lzma 2018-06-01T16:05:26.464Z,1527869126.464 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0007.lzma.bak 2018-06-01T16:05:26.464Z,1527869126.464 [DataOverHttps](INFO): SBD MOMSN=8303050 2018-06-01T16:05:27.699Z,1527869127.699 [Default:CheckIn:Read_Iridium] Stopped 2018-06-01T16:05:27.699Z,1527869127.699 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-01T16:05:27.699Z,1527869127.699 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-01T16:05:39.466Z,1527869139.466 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:05:39.466Z,1527869139.466 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 80 2018-06-01T16:05:39.466Z,1527869139.466 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:05:39.496Z,1527869139.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:05:39.905Z,1527869139.905 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:05:39.905Z,1527869139.905 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 80 2018-06-01T16:05:40.272Z,1527869140.272 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:05:40.272Z,1527869140.272 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:05:40.273Z,1527869140.273 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:06:15.818Z,1527869175.818 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-01T16:06:15.818Z,1527869175.818 [NAL9602] Data Fault, FailCount= 2 2018-06-01T16:06:15.818Z,1527869175.818 [NAL9602](ERROR): Data Fault 2018-06-01T16:06:15.888Z,1527869175.888 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-01T16:06:16.215Z,1527869176.215 [NAL9602](INFO): Powering down 2018-06-01T16:06:17.090Z,1527869177.090 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-01T16:06:17.090Z,1527869177.090 [NAL9602] No Fault, FailCount= 2 2018-06-01T16:06:37.838Z,1527869197.838 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:06:37.838Z,1527869197.838 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-01T16:06:37.845Z,1527869197.845 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:06:37.845Z,1527869197.845 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:06:37.845Z,1527869197.845 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:06:37.845Z,1527869197.845 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:06:41.969Z,1527869201.969 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T16:06:42.035Z,1527869202.035 [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-06-01T16:06:42.036Z,1527869202.036 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T16:06:42.037Z,1527869202.037 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T16:06:42.038Z,1527869202.038 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T16:06:46.490Z,1527869206.490 [NAL9602](INFO): Powering up NAL9602 2018-06-01T16:06:57.686Z,1527869217.686 [NAL9602](INFO): NAL9602 initialized 2018-06-01T16:08:21.706Z,1527869301.706 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:08:22.501Z,1527869302.501 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:08:24.292Z,1527869304.292 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T16:08:24.292Z,1527869304.292 [BPC1] Communications Fault, FailCount= 2 2018-06-01T16:08:24.292Z,1527869304.292 [BPC1](ERROR): Communications Fault 2018-06-01T16:08:24.347Z,1527869304.347 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T16:08:25.624Z,1527869305.624 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T16:08:25.624Z,1527869305.624 [BPC1] No Fault, FailCount= 2 2018-06-01T16:08:38.401Z,1527869318.401 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T16:08:38.401Z,1527869318.401 [Rowe_600LCM] Communications Fault, FailCount= 1 2018-06-01T16:08:38.401Z,1527869318.401 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T16:08:38.558Z,1527869318.558 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T16:08:38.805Z,1527869318.805 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:08:38.986Z,1527869318.986 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:08:40.556Z,1527869320.556 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:08:40.556Z,1527869320.556 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 81 2018-06-01T16:08:40.557Z,1527869320.557 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:08:40.579Z,1527869320.579 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:08:40.579Z,1527869320.579 [Rowe_600LCM] No Fault, FailCount= 1 2018-06-01T16:08:40.580Z,1527869320.580 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:08:40.601Z,1527869320.601 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:08:40.602Z,1527869320.602 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:08:40.602Z,1527869320.602 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:08:40.602Z,1527869320.602 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:08:40.965Z,1527869320.965 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:08:40.965Z,1527869320.965 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 81 2018-06-01T16:08:41.348Z,1527869321.348 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:08:41.349Z,1527869321.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:08:41.349Z,1527869321.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:08:44.723Z,1527869324.723 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T16:08:44.795Z,1527869324.795 [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-06-01T16:08:44.796Z,1527869324.796 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T16:08:44.797Z,1527869324.797 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T16:08:44.798Z,1527869324.798 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T16:10:10.870Z,1527869410.870 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:11.666Z,1527869411.666 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:12.462Z,1527869412.462 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:13.161Z,1527869413.161 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:13.957Z,1527869413.957 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:14.754Z,1527869414.754 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:15.189Z,1527869415.189 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:15.986Z,1527869415.986 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:16.621Z,1527869416.621 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:17.417Z,1527869417.417 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:18.214Z,1527869418.214 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:18.633Z,1527869418.633 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:19.429Z,1527869419.429 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:20.226Z,1527869420.226 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:20.645Z,1527869420.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:21.441Z,1527869421.441 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:22.239Z,1527869422.239 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:22.697Z,1527869422.697 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:23.493Z,1527869423.493 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:24.290Z,1527869424.290 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:24.889Z,1527869424.889 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:25.685Z,1527869425.685 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:26.481Z,1527869426.481 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:27.193Z,1527869427.193 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:27.989Z,1527869427.989 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:27.994Z,1527869427.994 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-01T16:10:27.994Z,1527869427.994 [Default:CheckIn:C.Wait] Stopped 2018-06-01T16:10:27.994Z,1527869427.994 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-01T16:10:27.994Z,1527869427.994 [Default:CheckIn:D] Running Loop=1 2018-06-01T16:10:28.402Z,1527869428.402 [Default:CheckIn:D] Stopped 2018-06-01T16:10:28.402Z,1527869428.402 [Default:CheckIn:E] Running Loop=1 2018-06-01T16:10:28.786Z,1527869428.786 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:28.836Z,1527869428.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.252116 min 2018-06-01T16:10:28.836Z,1527869428.836 [Default:CheckIn:E] Stopped 2018-06-01T16:10:28.836Z,1527869428.836 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn] Stopped 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn](INFO): Running loop #10 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn] Running Loop=10 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-01T16:10:28.841Z,1527869428.841 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-01T16:10:29.209Z,1527869429.209 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:30.006Z,1527869430.006 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:30.645Z,1527869430.645 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:31.441Z,1527869431.441 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:32.238Z,1527869432.238 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:32.657Z,1527869432.657 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:33.453Z,1527869433.453 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:34.250Z,1527869434.250 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:34.669Z,1527869434.669 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:35.465Z,1527869435.465 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:36.262Z,1527869436.262 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:36.725Z,1527869436.725 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:37.521Z,1527869437.521 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:38.319Z,1527869438.319 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:38.913Z,1527869438.913 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:39.709Z,1527869439.709 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:40.505Z,1527869440.505 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:10:40.805Z,1527869440.805 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T16:10:40.806Z,1527869440.806 [Rowe_600LCM] Communications Fault, FailCount= 2 2018-06-01T16:10:40.806Z,1527869440.806 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T16:10:40.818Z,1527869440.818 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T16:10:41.209Z,1527869441.209 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:10:41.306Z,1527869441.306 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:10:42.113Z,1527869442.113 [Rowe_600LCM](FAULT): LCB fault: Software Overcurrent. 2018-06-01T16:10:42.113Z,1527869442.113 [Rowe_600LCM] Hardware Fault, FailCount= 2 2018-06-01T16:10:42.113Z,1527869442.113 [Rowe_600LCM](ERROR): Hardware Fault 2018-06-01T16:10:42.764Z,1527869442.764 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:10:42.764Z,1527869442.764 [Rowe_600LCM] No Fault, FailCount= 2 2018-06-01T16:10:42.921Z,1527869442.921 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:10:42.921Z,1527869442.921 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:10:42.921Z,1527869442.921 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:10:42.922Z,1527869442.922 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:10:47.203Z,1527869447.203 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T16:10:47.355Z,1527869447.355 [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-06-01T16:10:47.356Z,1527869447.356 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T16:10:47.357Z,1527869447.357 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T16:10:47.358Z,1527869447.358 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T16:11:41.723Z,1527869501.723 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:11:41.723Z,1527869501.723 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 82 2018-06-01T16:11:41.724Z,1527869501.724 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:11:41.737Z,1527869501.737 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:11:42.141Z,1527869502.141 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:11:42.141Z,1527869502.141 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 82 2018-06-01T16:11:42.522Z,1527869502.522 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:11:42.523Z,1527869502.523 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:11:42.523Z,1527869502.523 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:12:41.106Z,1527869561.106 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:12:41.901Z,1527869561.901 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:12:43.004Z,1527869563.004 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T16:12:43.004Z,1527869563.004 [Rowe_600LCM] Communications Fault, FailCount= 3 2018-06-01T16:12:43.004Z,1527869563.004 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T16:12:43.106Z,1527869563.106 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T16:12:43.409Z,1527869563.409 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:12:43.571Z,1527869563.571 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:12:45.073Z,1527869565.073 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:12:45.073Z,1527869565.073 [Rowe_600LCM] No Fault, FailCount= 3 2018-06-01T16:12:45.185Z,1527869565.185 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:12:45.185Z,1527869565.185 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:12:45.186Z,1527869565.186 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:12:45.186Z,1527869565.186 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:12:49.315Z,1527869569.315 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T16:12:49.435Z,1527869569.435 [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-06-01T16:12:49.436Z,1527869569.436 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T16:12:49.437Z,1527869569.437 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T16:12:49.437Z,1527869569.437 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T16:14:29.266Z,1527869669.266 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:14:30.061Z,1527869670.061 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent. 2018-06-01T16:14:34.132Z,1527869674.132 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T16:14:34.132Z,1527869674.132 [BPC1] Communications Fault, FailCount= 3 2018-06-01T16:14:34.132Z,1527869674.132 [BPC1](ERROR): Communications Fault 2018-06-01T16:14:34.176Z,1527869674.176 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T16:14:35.085Z,1527869675.085 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T16:14:35.085Z,1527869675.085 [BPC1] No Fault, FailCount= 3 2018-06-01T16:14:42.637Z,1527869682.637 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:14:42.638Z,1527869682.638 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 83 2018-06-01T16:14:42.638Z,1527869682.638 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:14:42.670Z,1527869682.670 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:14:43.066Z,1527869683.066 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:14:43.066Z,1527869683.066 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 83 2018-06-01T16:14:43.454Z,1527869683.454 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:14:43.455Z,1527869683.455 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:14:43.455Z,1527869683.455 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:14:45.973Z,1527869685.973 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T16:14:45.973Z,1527869685.973 [Rowe_600LCM] Communications Fault, FailCount= 4 2018-06-01T16:14:45.973Z,1527869685.973 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T16:14:46.265Z,1527869686.265 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T16:14:46.377Z,1527869686.377 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:14:46.475Z,1527869686.475 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:14:47.848Z,1527869687.848 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:14:47.848Z,1527869687.848 [Rowe_600LCM] No Fault, FailCount= 4 2018-06-01T16:14:48.089Z,1527869688.089 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:14:48.089Z,1527869688.089 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:14:48.090Z,1527869688.090 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:14:48.090Z,1527869688.090 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:14:52.330Z,1527869692.330 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2018-06-01T16:14:52.396Z,1527869692.396 [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-06-01T16:14:52.397Z,1527869692.397 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2018-06-01T16:14:52.398Z,1527869692.398 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2018-06-01T16:14:52.400Z,1527869692.400 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2018-06-01T16:15:29.115Z,1527869729.115 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-01T16:10:28.8Z 2018-06-01T16:15:29.116Z,1527869729.116 [Default:CheckIn:Read_GPS] Stopped 2018-06-01T16:15:29.116Z,1527869729.116 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-01T16:15:43.855Z,1527869743.855 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Courier0009.lzma 2018-06-01T16:15:44.621Z,1527869744.621 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0009.lzma.bak 2018-06-01T16:15:44.621Z,1527869744.621 [DataOverHttps](INFO): SBD MOMSN=8303066 2018-06-01T16:16:20.341Z,1527869780.341 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20180601T154359/Express0010.lzma 2018-06-01T16:16:21.049Z,1527869781.049 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0010.lzma.bak 2018-06-01T16:16:21.049Z,1527869781.049 [DataOverHttps](INFO): SBD MOMSN=8303068 2018-06-01T16:16:24.745Z,1527869784.745 [Default:CheckIn:Read_Iridium] Stopped 2018-06-01T16:16:24.746Z,1527869784.746 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-01T16:16:24.746Z,1527869784.746 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-01T16:16:48.369Z,1527869808.369 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2018-06-01T16:16:48.369Z,1527869808.369 [Rowe_600LCM] Communications Fault, FailCount= 5 2018-06-01T16:16:48.369Z,1527869808.369 [Rowe_600LCM](ERROR): Communications Fault 2018-06-01T16:16:48.421Z,1527869808.421 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2018-06-01T16:16:48.422Z,1527869808.422 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2018-06-01T16:16:48.773Z,1527869808.773 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:16:48.906Z,1527869808.906 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:16:59.070Z,1527869819.070 [NAL9602](FAULT): GPS failed to acquire within timeout. 2018-06-01T16:16:59.070Z,1527869819.070 [NAL9602] Data Fault, FailCount= 3 2018-06-01T16:16:59.070Z,1527869819.070 [NAL9602](ERROR): Data Fault 2018-06-01T16:16:59.154Z,1527869819.154 [CBIT](ERROR): Data Fault in component: NAL9602 2018-06-01T16:16:59.474Z,1527869819.474 [NAL9602](INFO): Powering down 2018-06-01T16:17:00.320Z,1527869820.320 [CBIT](INFO): Clearing failed state for component NAL9602 2018-06-01T16:17:00.320Z,1527869820.320 [NAL9602] No Fault, FailCount= 3 2018-06-01T16:17:29.478Z,1527869849.478 [NAL9602](INFO): Powering up NAL9602 2018-06-01T16:17:40.678Z,1527869860.678 [NAL9602](INFO): NAL9602 initialized 2018-06-01T16:17:43.524Z,1527869863.524 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:17:43.524Z,1527869863.524 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 84 2018-06-01T16:17:43.524Z,1527869863.524 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:17:43.537Z,1527869863.537 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:17:43.943Z,1527869863.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:17:43.943Z,1527869863.943 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 84 2018-06-01T16:17:44.331Z,1527869864.331 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:17:44.332Z,1527869864.332 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:17:44.332Z,1527869864.332 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:20:43.912Z,1527870043.912 [BPC1](ERROR): Failed to receive data from both battery packs 2018-06-01T16:20:43.912Z,1527870043.912 [BPC1] Communications Fault, FailCount= 4 2018-06-01T16:20:43.912Z,1527870043.912 [BPC1](ERROR): Communications Fault 2018-06-01T16:20:43.938Z,1527870043.938 [CBIT](ERROR): Communications Fault in component: BPC1 2018-06-01T16:20:44.338Z,1527870044.338 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:20:44.338Z,1527870044.338 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 85 2018-06-01T16:20:44.338Z,1527870044.338 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:20:44.410Z,1527870044.410 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:20:44.759Z,1527870044.759 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:20:44.759Z,1527870044.759 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 85 2018-06-01T16:20:45.159Z,1527870045.159 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:20:45.160Z,1527870045.160 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:20:45.160Z,1527870045.160 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:20:45.206Z,1527870045.206 [CBIT](INFO): Clearing failed state for component BPC1 2018-06-01T16:20:45.207Z,1527870045.207 [BPC1] No Fault, FailCount= 4 2018-06-01T16:21:25.123Z,1527870085.123 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2018-06-01T16:21:25.123Z,1527870085.123 [Default:CheckIn:C.Wait] Stopped 2018-06-01T16:21:25.123Z,1527870085.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-01T16:21:25.124Z,1527870085.124 [Default:CheckIn:D] Running Loop=1 2018-06-01T16:21:25.614Z,1527870085.614 [Default:CheckIn:D] Stopped 2018-06-01T16:21:25.614Z,1527870085.614 [Default:CheckIn:E] Running Loop=1 2018-06-01T16:21:26.020Z,1527870086.020 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.205648 min 2018-06-01T16:21:26.020Z,1527870086.020 [Default:CheckIn:E] Stopped 2018-06-01T16:21:26.020Z,1527870086.020 [Default:CheckIn](INFO): Completed Default:CheckIn 2018-06-01T16:21:26.020Z,1527870086.020 [Default:CheckIn] Stopped 2018-06-01T16:21:26.020Z,1527870086.020 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-01T16:21:26.034Z,1527870086.034 [Default:CheckIn](INFO): Running loop #11 2018-06-01T16:21:26.034Z,1527870086.034 [Default:CheckIn] Running Loop=11 2018-06-01T16:21:26.035Z,1527870086.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2018-06-01T16:21:26.035Z,1527870086.035 [Default:CheckIn:Read_GPS] Running Loop=1 2018-06-01T16:23:45.431Z,1527870225.431 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:23:45.431Z,1527870225.431 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 86 2018-06-01T16:23:45.431Z,1527870225.431 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:23:45.445Z,1527870225.445 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:23:45.850Z,1527870225.850 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:23:45.850Z,1527870225.850 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 86 2018-06-01T16:23:46.199Z,1527870226.199 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:23:46.200Z,1527870226.200 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:23:46.200Z,1527870226.200 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:26:26.228Z,1527870386.228 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-06-01T16:21:26.0Z 2018-06-01T16:26:26.229Z,1527870386.229 [Default:CheckIn:Read_GPS] Stopped 2018-06-01T16:26:26.229Z,1527870386.229 [Default:CheckIn:Read_Iridium] Running Loop=1 2018-06-01T16:26:30.185Z,1527870390.185 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180601T154359/Courier0012.lzma 2018-06-01T16:26:30.984Z,1527870390.984 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Courier0012.lzma.bak 2018-06-01T16:26:30.984Z,1527870390.984 [DataOverHttps](INFO): SBD MOMSN=8303084 2018-06-01T16:26:40.269Z,1527870400.269 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20180601T154359/Express0013.lzma 2018-06-01T16:26:41.048Z,1527870401.048 [DataOverHttps](INFO): Moved sent file to Logs/20180601T154359/Express0013.lzma.bak 2018-06-01T16:26:41.048Z,1527870401.048 [DataOverHttps](INFO): SBD MOMSN=8303086 2018-06-01T16:26:42.301Z,1527870402.301 [Default:CheckIn:Read_Iridium] Stopped 2018-06-01T16:26:42.301Z,1527870402.301 [Default:CheckIn:C.Wait] Running Loop=1 2018-06-01T16:26:42.301Z,1527870402.301 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2018-06-01T16:26:46.411Z,1527870406.411 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2018-06-01T16:26:46.411Z,1527870406.411 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 87 2018-06-01T16:26:46.411Z,1527870406.411 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2018-06-01T16:26:46.425Z,1527870406.425 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2018-06-01T16:26:46.829Z,1527870406.829 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2018-06-01T16:26:46.830Z,1527870406.830 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 87 2018-06-01T16:26:47.184Z,1527870407.184 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2018-06-01T16:26:47.184Z,1527870407.184 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2018-06-01T16:26:47.185Z,1527870407.185 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2018-06-01T16:26:48.399Z,1527870408.399 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2018-06-01T16:26:48.399Z,1527870408.399 [Rowe_600LCM] No Fault, FailCount= 5 2018-06-01T16:26:48.693Z,1527870408.693 [Rowe_600LCM](INFO): Initializing 2018-06-01T16:26:48.693Z,1527870408.693 [Rowe_600LCM](INFO): Checking LCM 2018-06-01T16:26:48.693Z,1527870408.693 [Rowe_600LCM](INFO): LCM OK 2018-06-01T16:26:48.693Z,1527870408.693 [Rowe_600LCM](INFO): Powering up 2018-06-01T16:26:49.935Z,1527870409.935 [CommandLine](IMPORTANT): got command restart application 2018-06-01T16:26:50.949Z,1527870410.949 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:50.949Z,1527870410.949 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.125Z,1527870411.125 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2018-06-01T16:26:51.125Z,1527870411.125 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.126Z,1527870411.126 [CommandLine](INFO): Join timeout helper Thread ID is 1589 2018-06-01T16:26:51.127Z,1527870411.127 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2018-06-01T16:26:51.127Z,1527870411.127 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.127Z,1527870411.127 [NavChartDb](INFO): Join timeout helper Thread ID is 1590 2018-06-01T16:26:51.181Z,1527870411.181 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:51.181Z,1527870411.181 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.186Z,1527870411.186 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2018-06-01T16:26:51.186Z,1527870411.186 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.186Z,1527870411.186 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1591 2018-06-01T16:26:51.349Z,1527870411.349 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:51.349Z,1527870411.349 [WetLabsBB2FL](INFO): Powering down 2018-06-01T16:26:51.350Z,1527870411.350 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.372Z,1527870411.372 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2018-06-01T16:26:51.372Z,1527870411.372 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.372Z,1527870411.372 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1592 2018-06-01T16:26:51.581Z,1527870411.581 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:51.748Z,1527870411.748 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface 2018-06-01T16:26:51.749Z,1527870411.749 [CTD_Seabird](INFO): Powering down 2018-06-01T16:26:51.750Z,1527870411.750 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.761Z,1527870411.761 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler 2018-06-01T16:26:51.761Z,1527870411.761 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:51.762Z,1527870411.762 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 1594 2018-06-01T16:26:51.925Z,1527870411.925 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:51.925Z,1527870411.925 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2018-06-01T16:26:52.137Z,1527870412.137 [Rowe_600LCM](INFO): Powering down 2018-06-01T16:26:52.138Z,1527870412.138 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.146Z,1527870412.146 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2018-06-01T16:26:52.146Z,1527870412.146 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.147Z,1527870412.147 [Radio_Surface](INFO): Join timeout helper Thread ID is 1596 2018-06-01T16:26:52.189Z,1527870412.189 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:52.189Z,1527870412.189 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.198Z,1527870412.198 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2018-06-01T16:26:52.198Z,1527870412.198 [logger ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.199Z,1527870412.199 [logger](INFO): Join timeout helper Thread ID is 1597 2018-06-01T16:26:52.199Z,1527870412.199 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:52.199Z,1527870412.199 [logger ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.200Z,1527870412.200 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2018-06-01T16:26:52.201Z,1527870412.201 [CommandLine ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.201Z,1527870412.201 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2018-06-01T16:26:52.201Z,1527870412.201 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.201Z,1527870412.201 [controlThread](INFO): Join timeout helper Thread ID is 1598 2018-06-01T16:26:52.333Z,1527870412.333 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2018-06-01T16:26:52.333Z,1527870412.333 [controlThread](DEBUG): Uninitializing ControlThread 2018-06-01T16:26:52.334Z,1527870412.334 [AcousticModem_Benthos_ATM900](INFO): Powering down 2018-06-01T16:26:52.453Z,1527870412.453 [NAL9602](INFO): Powering down 2018-06-01T16:26:52.455Z,1527870412.455 [PNI_TCM](INFO): Powering down 2018-06-01T16:26:52.542Z,1527870412.542 [Aanderaa_O2](INFO): Powering down 2018-06-01T16:26:52.545Z,1527870412.545 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2018-06-01T16:26:52.546Z,1527870412.546 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2018-06-01T16:26:52.547Z,1527870412.547 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2018-06-01T16:26:52.547Z,1527870412.547 [MissionManager](INFO): Uninitializing Mission Default 2018-06-01T16:26:52.547Z,1527870412.547 [Default] Stopped 2018-06-01T16:26:52.548Z,1527870412.548 [Default](DEBUG): Aggregate::uninitialize Default 2018-06-01T16:26:52.548Z,1527870412.548 [Default:B.GoToSurface] Stopped 2018-06-01T16:26:52.548Z,1527870412.548 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2018-06-01T16:26:52.548Z,1527870412.548 [Default:CheckIn] Stopped 2018-06-01T16:26:52.548Z,1527870412.548 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2018-06-01T16:26:52.548Z,1527870412.548 [Default:CheckIn:C.Wait] Stopped 2018-06-01T16:26:52.548Z,1527870412.548 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2018-06-01T16:26:52.553Z,1527870412.553 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2018-06-01T16:26:52.553Z,1527870412.553 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2018-06-01T16:26:52.554Z,1527870412.554 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2018-06-01T16:26:52.554Z,1527870412.554 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2018-06-01T16:26:52.554Z,1527870412.554 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2018-06-01T16:26:52.555Z,1527870412.555 [BuoyancyServo](INFO): Powering down 2018-06-01T16:26:52.569Z,1527870412.569 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2018-06-01T16:26:52.569Z,1527870412.569 [ElevatorServo](INFO): Powering down 2018-06-01T16:26:52.570Z,1527870412.570 [MassServo](DEBUG): Uninitialize Mass Servo. 2018-06-01T16:26:52.570Z,1527870412.570 [MassServo](INFO): Powering down 2018-06-01T16:26:52.571Z,1527870412.571 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2018-06-01T16:26:52.571Z,1527870412.571 [RudderServo](INFO): Powering down 2018-06-01T16:26:52.572Z,1527870412.572 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2018-06-01T16:26:52.573Z,1527870412.573 [ThrusterServo](INFO): Powering down 2018-06-01T16:26:52.574Z,1527870412.574 [SBIT](DEBUG): Uninitialize SBIT Component. 2018-06-01T16:26:52.574Z,1527870412.574 [IBIT](DEBUG): Uninitialize IBIT Component. 2018-06-01T16:26:52.574Z,1527870412.574 [CBIT](DEBUG): Uninitialize CBIT Component. 2018-06-01T16:26:52.575Z,1527870412.575 [controlThread ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.681Z,1527870412.681 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.688Z,1527870412.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.733Z,1527870412.733 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.739Z,1527870412.739 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.800Z,1527870412.800 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2018-06-01T16:26:52.857Z,1527870412.857 [logger ThreadHandler](INFO): Thread cancelled.