2019-12-06T21:37:40.218Z,1575668260.218 [CommandLine](IMPORTANT): got command restart logs 2019-12-06T21:38:04.470Z,1575668284.470 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2019-12-06T21:38:04.470Z,1575668284.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-06T21:38:04.502Z,1575668284.502 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-06T21:38:04.919Z,1575668284.919 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-06T21:38:04.919Z,1575668284.919 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2019-12-06T21:39:35.390Z,1575668375.390 [CommandLine](IMPORTANT): got command failComponent 2019-12-06T21:39:35.390Z,1575668375.390 [CommandLine](IMPORTANT): Failed components: 2019-12-06T21:39:35.390Z,1575668375.390 [CommandLine](IMPORTANT): No failed Components. 2019-12-06T21:39:37.030Z,1575668377.030 [CommandLine](IMPORTANT): got command show stack 2019-12-06T21:39:37.031Z,1575668377.031 [CommandLine](IMPORTANT): Behavior Stack: 2019-12-06T21:39:37.031Z,1575668377.031 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface 2019-12-06T21:39:37.031Z,1575668377.031 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS 2019-12-06T21:40:02.010Z,1575668402.010 [NAL9602](FAULT): GPS failed to acquire within timeout. 2019-12-06T21:40:02.010Z,1575668402.010 [NAL9602] Data Fault, FailCount= 3 2019-12-06T21:40:02.010Z,1575668402.010 [NAL9602](ERROR): Data Fault 2019-12-06T21:40:02.046Z,1575668402.046 [CBIT](ERROR): Data Fault in component: NAL9602 2019-12-06T21:40:02.424Z,1575668402.424 [NAL9602](INFO): Powering down 2019-12-06T21:40:03.266Z,1575668403.266 [CBIT](INFO): Clearing failed state for component NAL9602 2019-12-06T21:40:03.266Z,1575668403.266 [NAL9602] No Fault, FailCount= 3 2019-12-06T21:40:32.718Z,1575668432.718 [NAL9602](INFO): Powering up NAL9602 2019-12-06T21:40:43.626Z,1575668443.626 [NAL9602](INFO): NAL9602 initialized 2019-12-06T21:41:05.472Z,1575668465.472 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2019-12-06T21:41:05.473Z,1575668465.473 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-06T21:41:05.482Z,1575668465.482 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-06T21:41:05.879Z,1575668465.879 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-06T21:41:05.879Z,1575668465.879 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2019-12-06T21:42:19.402Z,1575668539.402 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-12-06T21:37:19.2Z 2019-12-06T21:42:19.402Z,1575668539.402 [Default:CheckIn:Read_GPS] Stopped 2019-12-06T21:42:19.402Z,1575668539.402 [Default:CheckIn:Read_Iridium] Running Loop=1 2019-12-06T21:42:24.669Z,1575668544.669 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20191206T210745/Express0014.lzma 2019-12-06T21:42:26.674Z,1575668546.674 [DataOverHttps](INFO): Moved sent file to Logs/20191206T210745/Express0014.lzma.bak 2019-12-06T21:42:26.675Z,1575668546.675 [DataOverHttps](INFO): SBD MOMSN=12125712 2019-12-06T21:42:43.133Z,1575668563.133 [DataOverHttps](INFO): Sending 374 bytes from file Logs/20191206T213740/Express0001.lzma 2019-12-06T21:42:45.138Z,1575668565.138 [DataOverHttps](INFO): Moved sent file to Logs/20191206T213740/Express0001.lzma.bak 2019-12-06T21:42:45.138Z,1575668565.138 [DataOverHttps](INFO): SBD MOMSN=12125758 2019-12-06T21:42:46.481Z,1575668566.481 [Default:CheckIn:Read_Iridium] Stopped 2019-12-06T21:42:46.481Z,1575668566.481 [Default:CheckIn:C.Wait] Running Loop=1 2019-12-06T21:42:46.481Z,1575668566.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2019-12-06T21:43:10.586Z,1575668590.586 [CommandLine](IMPORTANT): got command show variable address 2019-12-06T21:43:10.588Z,1575668590.588 [CommandLine](IMPORTANT): acoustic_contact_address (unknown) 2019-12-06T21:43:10.613Z,1575668590.613 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count) 2019-12-06T21:43:10.614Z,1575668590.614 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum) 2019-12-06T21:43:10.614Z,1575668590.614 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum) 2019-12-06T21:43:10.616Z,1575668590.616 [CommandLine](IMPORTANT): DAT.localAddress (count) 2019-12-06T21:43:10.618Z,1575668590.618 [CommandLine](IMPORTANT): DAT.sbdAddress (enum) 2019-12-06T21:43:10.619Z,1575668590.619 [CommandLine](IMPORTANT): DAT.transponderAddress (enum) 2019-12-06T21:43:10.622Z,1575668590.622 [CommandLine](IMPORTANT): Micromodem.localAddress (enum) 2019-12-06T21:43:10.622Z,1575668590.622 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum) 2019-12-06T21:43:10.923Z,1575668590.923 [CommandLine](IMPORTANT): DAT.remoteAddress (enum) 2019-12-06T21:43:10.923Z,1575668590.923 [CommandLine](IMPORTANT): DAT.localAddressReading (enum) 2019-12-06T21:43:15.819Z,1575668595.819 [CommandLine](IMPORTANT): got command get DAT.localAddress 2019-12-06T21:43:15.819Z,1575668595.819 [CommandLine](IMPORTANT): DAT.localAddress 4 count 2019-12-06T21:43:20.698Z,1575668600.698 [CommandLine](IMPORTANT): got command configSet list 2019-12-06T21:43:20.698Z,1575668600.698 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): DAT.localAddress=4 count; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): DAT.verbosity=3 count; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): ESPComponent.sampleTimeout=0.5 minute; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2019-12-06T21:43:20.699Z,1575668600.699 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=198 cubic_centimeter; 2019-12-06T21:43:20.700Z,1575668600.700 [CommandLine](IMPORTANT): VerticalControl.massDefault=5 millimeter; 2019-12-06T21:43:20.700Z,1575668600.700 [CommandLine](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool; 2019-12-06T21:44:06.463Z,1575668646.463 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2019-12-06T21:44:06.463Z,1575668646.463 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2019-12-06T21:44:06.475Z,1575668646.475 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2019-12-06T21:44:06.880Z,1575668646.880 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2019-12-06T21:44:06.880Z,1575668646.880 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2019-12-06T21:44:22.738Z,1575668662.738 [CommandLine](IMPORTANT): got command quit 2019-12-06T21:44:23.741Z,1575668663.741 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:23.742Z,1575668663.742 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:23.937Z,1575668663.937 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2019-12-06T21:44:23.937Z,1575668663.937 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:23.938Z,1575668663.938 [CommandLine](INFO): Join timeout helper Thread ID is 851 2019-12-06T21:44:23.938Z,1575668663.938 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2019-12-06T21:44:23.938Z,1575668663.938 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:23.939Z,1575668663.939 [NavChartDb](INFO): Join timeout helper Thread ID is 852 2019-12-06T21:44:24.185Z,1575668664.185 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:24.185Z,1575668664.185 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:24.197Z,1575668664.197 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2019-12-06T21:44:24.197Z,1575668664.197 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:24.197Z,1575668664.197 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 853 2019-12-06T21:44:24.521Z,1575668664.521 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:24.521Z,1575668664.521 [WetLabsBB2FL](INFO): Powering down 2019-12-06T21:44:24.522Z,1575668664.522 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:24.537Z,1575668664.537 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2019-12-06T21:44:24.537Z,1575668664.537 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:24.537Z,1575668664.537 [CTD_Seabird](INFO): Join timeout helper Thread ID is 854 2019-12-06T21:44:25.517Z,1575668665.517 [CTD_Seabird](INFO): Powering down 2019-12-06T21:44:25.529Z,1575668665.529 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:25.529Z,1575668665.529 [CTD_Seabird](INFO): Powering down 2019-12-06T21:44:25.541Z,1575668665.541 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:25.553Z,1575668665.553 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2019-12-06T21:44:25.553Z,1575668665.553 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:25.553Z,1575668665.553 [BackSeatDriver](INFO): Join timeout helper Thread ID is 855 2019-12-06T21:44:25.773Z,1575668665.773 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:25.773Z,1575668665.773 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:25.794Z,1575668665.794 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2019-12-06T21:44:25.794Z,1575668665.794 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:25.794Z,1575668665.794 [Radio_Surface](INFO): Join timeout helper Thread ID is 856 2019-12-06T21:44:26.185Z,1575668666.185 [Radio_Surface](INFO): Powering down 2019-12-06T21:44:26.186Z,1575668666.186 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:26.186Z,1575668666.186 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.194Z,1575668666.194 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2019-12-06T21:44:26.194Z,1575668666.194 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.194Z,1575668666.194 [DataOverHttps](INFO): Join timeout helper Thread ID is 857 2019-12-06T21:44:26.241Z,1575668666.241 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:26.241Z,1575668666.241 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.262Z,1575668666.262 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2019-12-06T21:44:26.262Z,1575668666.262 [logger ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.262Z,1575668666.262 [logger](INFO): Join timeout helper Thread ID is 858 2019-12-06T21:44:26.285Z,1575668666.285 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:26.286Z,1575668666.286 [logger ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.306Z,1575668666.306 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2019-12-06T21:44:26.306Z,1575668666.306 [CommandLine ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.306Z,1575668666.306 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2019-12-06T21:44:26.306Z,1575668666.306 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.306Z,1575668666.306 [controlThread](INFO): Join timeout helper Thread ID is 859 2019-12-06T21:44:26.633Z,1575668666.633 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2019-12-06T21:44:26.633Z,1575668666.633 [controlThread](DEBUG): Uninitializing ControlThread 2019-12-06T21:44:26.634Z,1575668666.634 [AHRS_M2](INFO): Powering down 2019-12-06T21:44:26.705Z,1575668666.705 [NAL9602](INFO): Powering down 2019-12-06T21:44:26.707Z,1575668666.707 [DAT](INFO): Powering down 2019-12-06T21:44:26.826Z,1575668666.826 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2019-12-06T21:44:26.827Z,1575668666.827 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2019-12-06T21:44:26.827Z,1575668666.827 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2019-12-06T21:44:26.827Z,1575668666.827 [MissionManager](INFO): Uninitializing Mission Default 2019-12-06T21:44:26.828Z,1575668666.828 [Default] Stopped 2019-12-06T21:44:26.828Z,1575668666.828 [Default](DEBUG): Aggregate::uninitialize Default 2019-12-06T21:44:26.828Z,1575668666.828 [Default:B.GoToSurface] Stopped 2019-12-06T21:44:26.828Z,1575668666.828 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2019-12-06T21:44:26.828Z,1575668666.828 [Default:CheckIn] Stopped 2019-12-06T21:44:26.828Z,1575668666.828 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2019-12-06T21:44:26.828Z,1575668666.828 [Default:CheckIn:C.Wait] Stopped 2019-12-06T21:44:26.828Z,1575668666.828 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2019-12-06T21:44:26.830Z,1575668666.830 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2019-12-06T21:44:26.831Z,1575668666.831 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2019-12-06T21:44:26.831Z,1575668666.831 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2019-12-06T21:44:26.831Z,1575668666.831 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2019-12-06T21:44:26.831Z,1575668666.831 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2019-12-06T21:44:26.832Z,1575668666.832 [BuoyancyServo](INFO): Powering down 2019-12-06T21:44:26.845Z,1575668666.845 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-12-06T21:44:26.845Z,1575668666.845 [ElevatorServo](INFO): Powering down 2019-12-06T21:44:26.846Z,1575668666.846 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-12-06T21:44:26.846Z,1575668666.846 [MassServo](INFO): Powering down 2019-12-06T21:44:26.847Z,1575668666.847 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-12-06T21:44:26.847Z,1575668666.847 [RudderServo](INFO): Powering down 2019-12-06T21:44:26.847Z,1575668666.847 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-12-06T21:44:26.848Z,1575668666.848 [ThrusterServo](INFO): Powering down 2019-12-06T21:44:26.848Z,1575668666.848 [SBIT](DEBUG): Uninitialize SBIT Component. 2019-12-06T21:44:26.849Z,1575668666.849 [IBIT](DEBUG): Uninitialize IBIT Component. 2019-12-06T21:44:26.849Z,1575668666.849 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-12-06T21:44:26.849Z,1575668666.849 [CBIT](DEBUG): Powering off loads. 2019-12-06T21:44:26.861Z,1575668666.861 [CBIT](DEBUG): Disabling WDT. 2019-12-06T21:44:26.873Z,1575668666.873 [CBIT](DEBUG): Opening all GF detection circuits. 2019-12-06T21:44:26.874Z,1575668666.874 [controlThread ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.966Z,1575668666.966 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:26.972Z,1575668666.972 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:27.028Z,1575668667.028 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:27.032Z,1575668667.032 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:27.035Z,1575668667.035 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:27.045Z,1575668667.045 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2019-12-06T21:44:27.102Z,1575668667.102 [logger ThreadHandler](INFO): Thread cancelled.