2021-05-18T04:30:00.761Z,1621312200.761 [CommandLine](IMPORTANT): got command restart logs 2021-05-18T04:30:04.587Z,1621312204.587 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-05-18T04:30:04.587Z,1621312204.587 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-18T04:30:04.606Z,1621312204.606 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-18T04:30:04.994Z,1621312204.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-18T04:30:04.994Z,1621312204.994 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-05-18T04:30:51.035Z,1621312251.035 [AMEcho](INFO): Failure count cleared after critical for AMEcho 2021-05-18T04:31:44.357Z,1621312304.357 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-05-18T04:31:44.357Z,1621312304.357 [NAL9602] Data Fault, FailCount= 1 2021-05-18T04:31:44.357Z,1621312304.357 [NAL9602](ERROR): Data Fault 2021-05-18T04:31:44.382Z,1621312304.382 [CBIT](ERROR): Data Fault in component: NAL9602 2021-05-18T04:31:44.770Z,1621312304.770 [NAL9602](INFO): Powering down 2021-05-18T04:31:45.589Z,1621312305.589 [CBIT](INFO): Clearing failed state for component NAL9602 2021-05-18T04:31:45.589Z,1621312305.589 [NAL9602] No Fault, FailCount= 1 2021-05-18T04:32:15.060Z,1621312335.060 [NAL9602](INFO): Powering up NAL9602 2021-05-18T04:32:17.894Z,1621312337.894 [DAT](INFO): DAT read: user:11>Lowpower 2021-05-18T04:32:17.894Z,1621312337.894 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:11>Lowpower 2021-05-18T04:32:25.972Z,1621312345.972 [NAL9602](INFO): NAL9602 initialized 2021-05-18T04:33:05.569Z,1621312385.569 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-05-18T04:33:05.569Z,1621312385.569 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-18T04:33:05.579Z,1621312385.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-18T04:33:05.994Z,1621312385.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-18T04:33:05.994Z,1621312385.994 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-05-18T04:34:45.765Z,1621312485.765 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-05-18T04:29:45.6Z 2021-05-18T04:34:45.765Z,1621312485.765 [Default:CheckIn:Read_GPS] Stopped 2021-05-18T04:34:45.766Z,1621312485.766 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-05-18T04:34:46.178Z,1621312486.178 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-05-18T04:34:52.735Z,1621312492.735 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20210518T042051/Courier0004.lzma 2021-05-18T04:34:53.737Z,1621312493.737 [DataOverHttps](INFO): Moved sent file to Logs/20210518T042051/Courier0004.lzma.bak 2021-05-18T04:34:53.737Z,1621312493.737 [DataOverHttps](INFO): SBD MOMSN=15648313 2021-05-18T04:35:09.079Z,1621312509.079 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210518T043000/Courier0000.lzma 2021-05-18T04:35:10.081Z,1621312510.081 [DataOverHttps](INFO): Moved sent file to Logs/20210518T043000/Courier0000.lzma.bak 2021-05-18T04:35:10.081Z,1621312510.081 [DataOverHttps](INFO): SBD MOMSN=15648316 2021-05-18T04:35:26.861Z,1621312526.861 [DataOverHttps](INFO): Sending 548 bytes from file Logs/20210517T203015/Express0110.lzma 2021-05-18T04:35:27.862Z,1621312527.862 [DataOverHttps](INFO): Moved sent file to Logs/20210517T203015/Express0110.lzma.bak 2021-05-18T04:35:27.862Z,1621312527.862 [DataOverHttps](INFO): SBD MOMSN=15648319 2021-05-18T04:35:44.660Z,1621312544.660 [DataOverHttps](INFO): Sending 481 bytes from file Logs/20210518T024943/Express0001.lzma 2021-05-18T04:35:45.661Z,1621312545.661 [DataOverHttps](INFO): Moved sent file to Logs/20210518T024943/Express0001.lzma.bak 2021-05-18T04:35:45.661Z,1621312545.661 [DataOverHttps](INFO): SBD MOMSN=15648342 2021-05-18T04:36:02.527Z,1621312562.527 [DataOverHttps](INFO): Sending 336 bytes from file Logs/20210518T025202/Express0001.lzma 2021-05-18T04:36:03.529Z,1621312563.529 [DataOverHttps](INFO): Moved sent file to Logs/20210518T025202/Express0001.lzma.bak 2021-05-18T04:36:03.529Z,1621312563.529 [DataOverHttps](INFO): SBD MOMSN=15648349 2021-05-18T04:36:06.669Z,1621312566.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2021-05-18T04:36:06.669Z,1621312566.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-18T04:36:06.679Z,1621312566.679 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-18T04:36:06.981Z,1621312566.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-18T04:36:06.982Z,1621312566.982 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2021-05-18T04:36:20.440Z,1621312580.440 [DataOverHttps](INFO): Sending 896 bytes from file Logs/20210518T025530/Express0001.lzma 2021-05-18T04:36:21.441Z,1621312581.441 [DataOverHttps](INFO): Moved sent file to Logs/20210518T025530/Express0001.lzma.bak 2021-05-18T04:36:21.441Z,1621312581.441 [DataOverHttps](INFO): SBD MOMSN=15648355 2021-05-18T04:36:38.206Z,1621312598.206 [DataOverHttps](INFO): Sending 802 bytes from file Logs/20210518T042051/Express0001.lzma 2021-05-18T04:36:39.205Z,1621312599.205 [DataOverHttps](INFO): Moved sent file to Logs/20210518T042051/Express0001.lzma.bak 2021-05-18T04:36:39.205Z,1621312599.205 [DataOverHttps](INFO): SBD MOMSN=15648378 2021-05-18T04:36:55.984Z,1621312615.984 [DataOverHttps](INFO): Sending 2293 bytes from file Logs/20210518T042051/Express0005.lzma 2021-05-18T04:36:56.981Z,1621312616.981 [DataOverHttps](INFO): Moved sent file to Logs/20210518T042051/Express0005.lzma.bak 2021-05-18T04:36:56.981Z,1621312616.981 [DataOverHttps](INFO): SBD MOMSN=15648396 2021-05-18T04:37:13.776Z,1621312633.776 [DataOverHttps](INFO): Sending 294 bytes from file Logs/20210518T043000/Express0001.lzma 2021-05-18T04:37:14.777Z,1621312634.777 [DataOverHttps](INFO): Moved sent file to Logs/20210518T043000/Express0001.lzma.bak 2021-05-18T04:37:14.777Z,1621312634.777 [DataOverHttps](INFO): SBD MOMSN=15648551 2021-05-18T04:37:17.279Z,1621312637.279 [Default:CheckIn:Read_Iridium] Stopped 2021-05-18T04:37:17.280Z,1621312637.280 [Default:CheckIn:C.Wait] Running Loop=1 2021-05-18T04:37:17.280Z,1621312637.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-05-18T04:39:07.560Z,1621312747.560 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2021-05-18T04:39:07.560Z,1621312747.560 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-18T04:39:07.592Z,1621312747.592 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-18T04:39:07.978Z,1621312747.978 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-18T04:39:07.978Z,1621312747.978 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2021-05-18T04:42:08.588Z,1621312928.588 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2021-05-18T04:42:08.588Z,1621312928.588 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-05-18T04:42:08.599Z,1621312928.599 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-05-18T04:42:09.010Z,1621312929.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-05-18T04:42:09.010Z,1621312929.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2021-05-18T04:42:17.882Z,1621312937.882 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2021-05-18T04:42:17.882Z,1621312937.882 [Default:CheckIn:C.Wait] Stopped 2021-05-18T04:42:17.882Z,1621312937.882 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2021-05-18T04:42:17.882Z,1621312937.882 [Default:CheckIn:D] Running Loop=1 2021-05-18T04:42:18.285Z,1621312938.285 [Default:CheckIn:D] Stopped 2021-05-18T04:42:18.285Z,1621312938.285 [Default:CheckIn:E] Running Loop=1 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.773802 min 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn:E] Stopped 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn](INFO): Completed Default:CheckIn 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn] Stopped 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn](INFO): Running loop #2 2021-05-18T04:42:18.701Z,1621312938.701 [Default:CheckIn] Running Loop=2 2021-05-18T04:42:18.702Z,1621312938.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-05-18T04:42:18.702Z,1621312938.702 [Default:CheckIn:Read_GPS] Running Loop=1 2021-05-18T04:42:28.372Z,1621312948.372 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-05-18T04:42:28.372Z,1621312948.372 [NAL9602] Data Fault, FailCount= 2 2021-05-18T04:42:28.372Z,1621312948.372 [NAL9602](ERROR): Data Fault 2021-05-18T04:42:28.427Z,1621312948.427 [CBIT](ERROR): Data Fault in component: NAL9602 2021-05-18T04:42:28.776Z,1621312948.776 [NAL9602](INFO): Powering down 2021-05-18T04:42:29.611Z,1621312949.611 [CBIT](INFO): Clearing failed state for component NAL9602 2021-05-18T04:42:29.611Z,1621312949.611 [NAL9602] No Fault, FailCount= 2 2021-05-18T04:42:59.088Z,1621312979.088 [NAL9602](INFO): Powering up NAL9602 2021-05-18T04:43:09.996Z,1621312989.996 [NAL9602](INFO): NAL9602 initialized 2021-05-18T04:44:39.376Z,1621313079.376 [CommandLine](IMPORTANT): got command restart application 2021-05-18T04:44:40.379Z,1621313080.379 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:40.380Z,1621313080.380 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:40.519Z,1621313080.519 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-05-18T04:44:40.519Z,1621313080.519 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:40.520Z,1621313080.520 [CommandLine](INFO): Join timeout helper Thread ID is 4934 2021-05-18T04:44:40.520Z,1621313080.520 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-05-18T04:44:40.520Z,1621313080.520 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:40.521Z,1621313080.521 [NavChartDb](INFO): Join timeout helper Thread ID is 4935 2021-05-18T04:44:40.707Z,1621313080.707 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:40.707Z,1621313080.707 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:40.719Z,1621313080.719 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-05-18T04:44:40.719Z,1621313080.719 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:40.719Z,1621313080.719 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4936 2021-05-18T04:44:41.147Z,1621313081.147 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:41.147Z,1621313081.147 [WetLabsBB2FL](INFO): Powering down 2021-05-18T04:44:41.148Z,1621313081.148 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.159Z,1621313081.159 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-05-18T04:44:41.159Z,1621313081.159 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.159Z,1621313081.159 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4937 2021-05-18T04:44:41.251Z,1621313081.251 [CTD_Seabird](INFO): Powering down 2021-05-18T04:44:41.263Z,1621313081.263 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:41.263Z,1621313081.263 [CTD_Seabird](INFO): Powering down 2021-05-18T04:44:41.279Z,1621313081.279 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.299Z,1621313081.299 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-05-18T04:44:41.299Z,1621313081.299 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.299Z,1621313081.299 [Radio_Surface](INFO): Join timeout helper Thread ID is 4938 2021-05-18T04:44:41.411Z,1621313081.411 [Radio_Surface](INFO): Powering down 2021-05-18T04:44:41.412Z,1621313081.412 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:41.412Z,1621313081.412 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.435Z,1621313081.435 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-05-18T04:44:41.435Z,1621313081.435 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:41.435Z,1621313081.435 [Onboard](INFO): Join timeout helper Thread ID is 4939 2021-05-18T04:44:41.551Z,1621313081.551 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2021-05-18T04:44:43.587Z,1621313083.587 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:43.587Z,1621313083.587 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:43.596Z,1621313083.596 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2021-05-18T04:44:43.596Z,1621313083.596 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:43.596Z,1621313083.596 [DataOverHttps](INFO): Join timeout helper Thread ID is 4940 2021-05-18T04:44:44.563Z,1621313084.563 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:44.563Z,1621313084.563 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:44.568Z,1621313084.568 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2021-05-18T04:44:44.568Z,1621313084.568 [logger ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:44.568Z,1621313084.568 [logger](INFO): Join timeout helper Thread ID is 4941 2021-05-18T04:44:44.590Z,1621313084.590 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:44.590Z,1621313084.590 [logger ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:44.600Z,1621313084.600 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2021-05-18T04:44:44.600Z,1621313084.600 [CommandLine ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:44.600Z,1621313084.600 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2021-05-18T04:44:44.600Z,1621313084.600 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:44.600Z,1621313084.600 [controlThread](INFO): Join timeout helper Thread ID is 4942 2021-05-18T04:44:44.923Z,1621313084.923 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2021-05-18T04:44:44.923Z,1621313084.923 [controlThread](DEBUG): Uninitializing ControlThread 2021-05-18T04:44:44.924Z,1621313084.924 [AHRS_M2](INFO): Powering down 2021-05-18T04:44:44.996Z,1621313084.996 [NAL9602](INFO): Powering down 2021-05-18T04:44:45.163Z,1621313085.163 [AMEcho](INFO): Powering down. 2021-05-18T04:44:45.164Z,1621313085.164 [DAT](INFO): Powering down 2021-05-18T04:44:45.284Z,1621313085.284 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2021-05-18T04:44:45.285Z,1621313085.285 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2021-05-18T04:44:45.285Z,1621313085.285 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2021-05-18T04:44:45.286Z,1621313085.286 [MissionManager](INFO): Uninitializing Mission Default 2021-05-18T04:44:45.286Z,1621313085.286 [Default] Stopped 2021-05-18T04:44:45.286Z,1621313085.286 [Default](DEBUG): Aggregate::uninitialize Default 2021-05-18T04:44:45.286Z,1621313085.286 [Default:B.GoToSurface] Stopped 2021-05-18T04:44:45.286Z,1621313085.286 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-05-18T04:44:45.286Z,1621313085.286 [Default:CheckIn] Stopped 2021-05-18T04:44:45.286Z,1621313085.286 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2021-05-18T04:44:45.286Z,1621313085.286 [Default:CheckIn:Read_GPS] Stopped 2021-05-18T04:44:45.289Z,1621313085.289 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2021-05-18T04:44:45.289Z,1621313085.289 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2021-05-18T04:44:45.290Z,1621313085.290 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2021-05-18T04:44:45.290Z,1621313085.290 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2021-05-18T04:44:45.290Z,1621313085.290 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2021-05-18T04:44:45.290Z,1621313085.290 [BuoyancyServo](INFO): Powering down 2021-05-18T04:44:45.303Z,1621313085.303 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-05-18T04:44:45.303Z,1621313085.303 [ElevatorServo](INFO): Powering down 2021-05-18T04:44:45.304Z,1621313085.304 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-05-18T04:44:45.304Z,1621313085.304 [MassServo](INFO): Powering down 2021-05-18T04:44:45.305Z,1621313085.305 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-05-18T04:44:45.305Z,1621313085.305 [RudderServo](INFO): Powering down 2021-05-18T04:44:45.306Z,1621313085.306 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-05-18T04:44:45.306Z,1621313085.306 [ThrusterServo](INFO): Powering down 2021-05-18T04:44:45.306Z,1621313085.306 [SBIT](DEBUG): Uninitialize SBIT Component. 2021-05-18T04:44:45.307Z,1621313085.307 [IBIT](DEBUG): Uninitialize IBIT Component. 2021-05-18T04:44:45.307Z,1621313085.307 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-05-18T04:44:45.307Z,1621313085.307 [CBIT](DEBUG): Powering off loads. 2021-05-18T04:44:45.319Z,1621313085.319 [CBIT](DEBUG): Disabling WDT. 2021-05-18T04:44:45.331Z,1621313085.331 [CBIT](DEBUG): Opening all GF detection circuits. 2021-05-18T04:44:45.332Z,1621313085.332 [controlThread ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.377Z,1621313085.377 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.380Z,1621313085.380 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.385Z,1621313085.385 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.460Z,1621313085.460 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.464Z,1621313085.464 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.501Z,1621313085.501 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-05-18T04:44:45.562Z,1621313085.562 [logger ThreadHandler](INFO): Thread cancelled.