2021-11-18T23:58:25.036Z,1637279905.036 [Supervisor](DEBUG): Initializing supervisor. 2021-11-18T23:58:25.041Z,1637279905.041 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-18T23:58:25.042Z,1637279905.042 [SyncHandler](INFO): Protected caller Thread ID is 1076 2021-11-18T23:58:25.042Z,1637279905.042 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-11-18T23:58:25.043Z,1637279905.043 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-11-18T23:58:25.044Z,1637279905.044 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1077 2021-11-18T23:58:25.048Z,1637279905.048 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-11-18T23:58:25.067Z,1637279905.067 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2021-11-18T23:58:25.068Z,1637279905.068 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-11-18T23:58:25.068Z,1637279905.068 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1078 2021-11-18T23:58:25.072Z,1637279905.072 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-11-18T23:58:25.073Z,1637279905.073 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-11-18T23:58:25.074Z,1637279905.074 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1079 2021-11-18T23:58:25.076Z,1637279905.076 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-11-18T23:58:25.077Z,1637279905.077 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2021-11-18T23:58:25.077Z,1637279905.077 [logger ThreadHandler](INFO): Protected caller Thread ID is 1080 2021-11-18T23:58:25.081Z,1637279905.081 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-11-18T23:58:25.081Z,1637279905.081 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-11-18T23:58:25.083Z,1637279905.083 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-11-18T23:58:25.307Z,1637279905.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-11-18T23:58:25.308Z,1637279905.308 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-11-18T23:58:25.389Z,1637279905.389 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-11-18T23:58:25.888Z,1637279905.888 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-11-18T23:58:25.889Z,1637279905.889 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-11-18T23:58:26.332Z,1637279906.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-11-18T23:58:26.332Z,1637279906.332 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-11-18T23:58:26.428Z,1637279906.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-11-18T23:58:26.428Z,1637279906.428 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-11-18T23:58:26.783Z,1637279906.783 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-11-18T23:58:26.784Z,1637279906.784 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-11-18T23:58:26.977Z,1637279906.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-11-18T23:58:26.977Z,1637279906.977 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-11-18T23:58:27.424Z,1637279907.424 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-11-18T23:58:27.424Z,1637279907.424 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-11-18T23:58:27.551Z,1637279907.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-11-18T23:58:27.551Z,1637279907.551 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-11-18T23:58:27.630Z,1637279907.630 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-11-18T23:58:28.280Z,1637279908.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-11-18T23:58:28.281Z,1637279908.281 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-11-18T23:58:28.633Z,1637279908.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-11-18T23:58:28.634Z,1637279908.634 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-11-18T23:58:28.820Z,1637279908.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-11-18T23:58:28.820Z,1637279908.820 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-11-18T23:58:28.929Z,1637279908.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-11-18T23:58:28.930Z,1637279908.930 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-11-18T23:58:29.078Z,1637279909.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-11-18T23:58:29.079Z,1637279909.079 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-11-18T23:58:29.300Z,1637279909.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-11-18T23:58:29.302Z,1637279909.302 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2021-11-18T23:58:29.303Z,1637279909.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2021-11-18T23:58:29.393Z,1637279909.393 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2021-11-18T23:58:29.478Z,1637279909.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2021-11-18T23:58:29.578Z,1637279909.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2021-11-18T23:58:29.659Z,1637279909.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2021-11-18T23:58:29.753Z,1637279909.753 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2021-11-18T23:58:29.853Z,1637279909.853 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2021-11-18T23:58:30.033Z,1637279910.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg 2021-11-18T23:58:30.108Z,1637279910.108 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2021-11-18T23:58:30.252Z,1637279910.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2021-11-18T23:58:30.386Z,1637279910.386 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2021-11-18T23:58:30.500Z,1637279910.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2021-11-18T23:58:30.757Z,1637279910.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-11-18T23:58:30.759Z,1637279910.759 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2021-11-18T23:58:30.759Z,1637279910.759 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-11-18T23:58:30.772Z,1637279910.772 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-11-18T23:58:30.827Z,1637279910.827 [VerticalControl](DEBUG): Construct VerticalControl. 2021-11-18T23:58:30.889Z,1637279910.889 [VerticalControl] Loaded 2021-11-18T23:58:30.890Z,1637279910.890 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-11-18T23:58:30.892Z,1637279910.892 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-11-18T23:58:30.931Z,1637279910.931 [HorizontalControl] Loaded 2021-11-18T23:58:30.931Z,1637279910.931 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-11-18T23:58:30.933Z,1637279910.933 [SpeedControl](DEBUG): Construct SpeedControl. 2021-11-18T23:58:30.936Z,1637279910.936 [SpeedControl] Loaded 2021-11-18T23:58:30.937Z,1637279910.937 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-11-18T23:58:30.939Z,1637279910.939 [LoopControl](DEBUG): Construct LoopControl. 2021-11-18T23:58:30.940Z,1637279910.940 [LoopControl] Loaded 2021-11-18T23:58:30.940Z,1637279910.940 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-11-18T23:58:30.940Z,1637279910.940 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-11-18T23:58:30.941Z,1637279910.941 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-11-18T23:58:30.952Z,1637279910.952 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-11-18T23:58:30.952Z,1637279910.952 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-11-18T23:58:31.059Z,1637279911.059 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-11-18T23:58:31.059Z,1637279911.059 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-11-18T23:58:31.213Z,1637279911.213 [BuoyancyServo] Loaded 2021-11-18T23:58:31.213Z,1637279911.213 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-11-18T23:58:31.233Z,1637279911.233 [ElevatorServo] Loaded 2021-11-18T23:58:31.233Z,1637279911.233 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-11-18T23:58:31.251Z,1637279911.251 [MassServo] Loaded 2021-11-18T23:58:31.251Z,1637279911.251 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-11-18T23:58:31.269Z,1637279911.269 [RudderServo] Loaded 2021-11-18T23:58:31.269Z,1637279911.269 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-11-18T23:58:31.287Z,1637279911.287 [ThrusterServo] Loaded 2021-11-18T23:58:31.287Z,1637279911.287 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-11-18T23:58:31.288Z,1637279911.288 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-11-18T23:58:31.288Z,1637279911.288 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-11-18T23:58:31.370Z,1637279911.370 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-11-18T23:58:31.370Z,1637279911.370 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-11-18T23:58:31.384Z,1637279911.384 [NavChart] Loaded 2021-11-18T23:58:31.384Z,1637279911.384 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-11-18T23:58:31.390Z,1637279911.390 [UniversalFixResidualReporter] Loaded 2021-11-18T23:58:31.390Z,1637279911.390 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-11-18T23:58:31.391Z,1637279911.391 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-11-18T23:58:31.391Z,1637279911.391 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-11-18T23:58:31.505Z,1637279911.505 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-11-18T23:58:31.506Z,1637279911.506 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-11-18T23:58:32.458Z,1637279912.458 [AHRS_M2] Loaded 2021-11-18T23:58:32.458Z,1637279912.458 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-11-18T23:58:33.247Z,1637279913.247 [BPC1] Loaded 2021-11-18T23:58:33.247Z,1637279913.247 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-11-18T23:58:33.267Z,1637279913.267 [Depth_Keller] Loaded 2021-11-18T23:58:33.267Z,1637279913.267 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-11-18T23:58:33.272Z,1637279913.272 [DropWeight] Loaded 2021-11-18T23:58:33.272Z,1637279913.272 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-11-18T23:58:33.331Z,1637279913.331 [NAL9602] Loaded 2021-11-18T23:58:33.331Z,1637279913.331 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-11-18T23:58:33.360Z,1637279913.360 [Onboard] Loaded 2021-11-18T23:58:33.360Z,1637279913.360 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-11-18T23:58:33.362Z,1637279913.362 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408C64E0 2021-11-18T23:58:33.362Z,1637279913.362 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1165 2021-11-18T23:58:33.375Z,1637279913.375 [Power24vConverter] Loaded 2021-11-18T23:58:33.376Z,1637279913.376 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-11-18T23:58:33.390Z,1637279913.390 [Radio_Surface] Loaded 2021-11-18T23:58:33.390Z,1637279913.390 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-11-18T23:58:33.391Z,1637279913.391 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408F64E0 2021-11-18T23:58:33.391Z,1637279913.391 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1166 2021-11-18T23:58:33.473Z,1637279913.473 [DAT] Loaded 2021-11-18T23:58:33.473Z,1637279913.473 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-11-18T23:58:33.473Z,1637279913.473 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-11-18T23:58:33.474Z,1637279913.474 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-11-18T23:58:33.516Z,1637279913.516 [DepthRateCalculator] Loaded 2021-11-18T23:58:33.517Z,1637279913.517 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-11-18T23:58:33.522Z,1637279913.522 [PitchRateCalculator] Loaded 2021-11-18T23:58:33.522Z,1637279913.522 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-11-18T23:58:33.531Z,1637279913.531 [SpeedCalculator] Loaded 2021-11-18T23:58:33.531Z,1637279913.531 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-11-18T23:58:33.545Z,1637279913.545 [TempGradientCalculator] Loaded 2021-11-18T23:58:33.545Z,1637279913.545 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-11-18T23:58:33.550Z,1637279913.550 [YawRateCalculator] Loaded 2021-11-18T23:58:33.550Z,1637279913.550 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-11-18T23:58:33.568Z,1637279913.568 [ElevatorOffsetCalculator] Loaded 2021-11-18T23:58:33.568Z,1637279913.568 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-11-18T23:58:33.569Z,1637279913.569 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-11-18T23:58:33.569Z,1637279913.569 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-11-18T23:58:33.702Z,1637279913.702 [CANONSampler] Loaded 2021-11-18T23:58:33.702Z,1637279913.702 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2021-11-18T23:58:33.761Z,1637279913.761 [CTD_Seabird] Loaded 2021-11-18T23:58:33.761Z,1637279913.761 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-11-18T23:58:33.762Z,1637279913.762 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0 2021-11-18T23:58:33.762Z,1637279913.762 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1167 2021-11-18T23:58:33.782Z,1637279913.782 [PAR_Licor] Loaded 2021-11-18T23:58:33.783Z,1637279913.783 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-11-18T23:58:33.815Z,1637279913.815 [WetLabsBB2FL] Loaded 2021-11-18T23:58:33.815Z,1637279913.815 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-11-18T23:58:33.816Z,1637279913.816 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0 2021-11-18T23:58:33.816Z,1637279913.816 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1168 2021-11-18T23:58:33.817Z,1637279913.817 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-11-18T23:58:33.818Z,1637279913.818 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-11-18T23:58:33.845Z,1637279913.845 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-11-18T23:58:33.846Z,1637279913.846 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-11-18T23:58:34.173Z,1637279914.173 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-11-18T23:58:34.174Z,1637279914.174 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-11-18T23:58:34.324Z,1637279914.324 [SBIT](DEBUG): Construct Startup Built In Test. 2021-11-18T23:58:34.333Z,1637279914.333 [SBIT] Loaded 2021-11-18T23:58:34.333Z,1637279914.333 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-11-18T23:58:34.336Z,1637279914.336 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-11-18T23:58:34.349Z,1637279914.349 [IBIT] Loaded 2021-11-18T23:58:34.350Z,1637279914.350 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-11-18T23:58:34.355Z,1637279914.355 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-11-18T23:58:34.460Z,1637279914.460 [CBIT] Loaded 2021-11-18T23:58:34.460Z,1637279914.460 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-11-18T23:58:34.460Z,1637279914.460 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-11-18T23:58:34.461Z,1637279914.461 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-11-18T23:58:34.557Z,1637279914.557 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-11-18T23:58:34.563Z,1637279914.563 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-11-18T23:58:34.566Z,1637279914.566 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-11-18T23:58:34.576Z,1637279914.576 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-11-18T23:58:34.577Z,1637279914.577 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0 2021-11-18T23:58:34.578Z,1637279914.578 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1169 2021-11-18T23:58:34.582Z,1637279914.582 [Supervisor](INFO): Main Thread ID is 830 2021-11-18T23:58:34.582Z,1637279914.582 [Supervisor](DEBUG): Running supervisor. 2021-11-18T23:58:34.583Z,1637279914.583 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1170 2021-11-18T23:58:34.584Z,1637279914.584 [CommandExec](INFO): Initializing the command executive. 2021-11-18T23:58:34.585Z,1637279914.585 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1171 2021-11-18T23:58:34.588Z,1637279914.588 [controlThread ThreadHandler](INFO): Handler Thread ID is 1172 2021-11-18T23:58:34.588Z,1637279914.588 [controlThread](DEBUG): Initializing ControlThread 2021-11-18T23:58:34.589Z,1637279914.589 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-11-18T23:58:34.591Z,1637279914.591 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-11-18T23:58:34.592Z,1637279914.592 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-11-18T23:58:34.592Z,1637279914.592 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-11-18T23:58:34.594Z,1637279914.594 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-11-18T23:58:34.595Z,1637279914.595 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-11-18T23:58:34.599Z,1637279914.599 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-11-18T23:58:34.600Z,1637279914.600 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-11-18T23:58:34.600Z,1637279914.600 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-11-18T23:58:34.600Z,1637279914.600 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-11-18T23:58:34.601Z,1637279914.601 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-11-18T23:58:34.601Z,1637279914.601 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-11-18T23:58:34.605Z,1637279914.605 [SBIT](INFO): Initialize SBIT Component. 2021-11-18T23:58:34.606Z,1637279914.606 [SBIT](IMPORTANT): git: 2021-11-01-22-gea1679f0f 2021-11-18T23:58:34.606Z,1637279914.606 [SBIT](INFO): git hash: ea1679f0fbc9f895a669b42c70dfc339166fc8a2 2021-11-18T23:58:34.606Z,1637279914.606 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-11-18T23:58:34.607Z,1637279914.607 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2021-11-18T23:58:34.608Z,1637279914.608 [SBIT](INFO): Beginning SBIT in 84.000000 seconds. 2021-11-18T23:58:34.609Z,1637279914.609 [IBIT](INFO): Initialize IBIT Component. 2021-11-18T23:58:34.610Z,1637279914.610 [CBIT](DEBUG): Initialize CBIT Component. 2021-11-18T23:58:34.611Z,1637279914.611 [logger ThreadHandler](INFO): Handler Thread ID is 1173 2021-11-18T23:58:34.621Z,1637279914.621 [CBIT](DEBUG): Initialized mux pins. 2021-11-18T23:58:34.621Z,1637279914.621 [CBIT](DEBUG): Initializing the watchdog timer. 2021-11-18T23:58:34.629Z,1637279914.629 [Onboard ThreadHandler](INFO): Handler Thread ID is 1174 2021-11-18T23:58:34.645Z,1637279914.645 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-11-18T23:58:34.646Z,1637279914.646 [CBIT](DEBUG): Initializing heartbeat. 2021-11-18T23:58:34.651Z,1637279914.651 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1175 2021-11-18T23:58:34.665Z,1637279914.665 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1176 2021-11-18T23:58:34.666Z,1637279914.666 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-11-18T23:58:34.669Z,1637279914.669 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1178 2021-11-18T23:58:34.673Z,1637279914.673 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1180 2021-11-18T23:58:34.674Z,1637279914.674 [WetLabsBB2FL](INFO): Powering up 2021-11-18T23:58:34.678Z,1637279914.678 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-11-18T23:58:34.678Z,1637279914.678 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-11-18T23:58:34.678Z,1637279914.678 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-11-18T23:58:34.679Z,1637279914.679 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-11-18T23:58:34.679Z,1637279914.679 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-11-18T23:58:34.679Z,1637279914.679 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-11-18T23:58:34.679Z,1637279914.679 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-11-18T23:58:34.679Z,1637279914.679 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-11-18T23:58:34.680Z,1637279914.680 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-11-18T23:58:34.681Z,1637279914.681 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-11-18T23:58:34.682Z,1637279914.682 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-11-18T23:58:34.717Z,1637279914.717 [CBIT](DEBUG): Deactivating GF circuits. 2021-11-18T23:58:34.717Z,1637279914.717 [CBIT](DEBUG): Deactivating emergency mode. 2021-11-18T23:58:34.753Z,1637279914.753 [CBIT](DEBUG): Backplane powered. 2021-11-18T23:58:34.754Z,1637279914.754 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-11-18T23:58:34.767Z,1637279914.767 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-18T23:58:34.787Z,1637279914.787 [MissionManager](DEBUG): 2021-11-18T23:58:34.788Z,1637279914.788 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-11-18T23:58:34.867Z,1637279914.867 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-11-18T23:58:34.868Z,1637279914.868 [Default:A.Wait](DEBUG): Construct Wait. 2021-11-18T23:58:34.887Z,1637279914.887 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-18T23:58:34.915Z,1637279914.915 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-11-18T23:58:34.941Z,1637279914.941 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-11-18T23:58:34.947Z,1637279914.947 [Default:E.Execute](DEBUG): Construct Execute. 2021-11-18T23:58:34.967Z,1637279914.967 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 1 Default mission has been running for Restarting logs and Default mission. restart logs 2021-11-18T23:58:34.972Z,1637279914.972 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,DAT,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2021-11-18T23:58:34.994Z,1637279914.994 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-11-18T23:58:35.053Z,1637279915.053 [Radio_Surface](INFO): Powering up 2021-11-18T23:58:35.085Z,1637279915.085 [Power24vConverter](INFO): Powering up. 2021-11-18T23:58:35.086Z,1637279915.086 [DAT](INFO): Powering up 2021-11-18T23:58:35.086Z,1637279915.086 [DAT](DEBUG): Initializing DAT. 2021-11-18T23:58:35.105Z,1637279915.105 [CANONSampler](INFO): Powering down 2021-11-18T23:58:35.156Z,1637279915.156 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:35.161Z,1637279915.161 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-11-18T23:58:35.162Z,1637279915.162 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:35.169Z,1637279915.169 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-11-18T23:58:35.170Z,1637279915.170 [MassServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:35.177Z,1637279915.177 [MassServo](DEBUG): Initializing MassServo. 2021-11-18T23:58:35.178Z,1637279915.178 [RudderServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:35.185Z,1637279915.185 [RudderServo](DEBUG): Initializing RudderServo. 2021-11-18T23:58:35.186Z,1637279915.186 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:35.201Z,1637279915.201 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-11-18T23:58:35.401Z,1637279915.401 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-11-18T23:58:35.401Z,1637279915.401 [DropWeight] Hardware Fault, FailCount= 1 2021-11-18T23:58:35.401Z,1637279915.401 [DropWeight](ERROR): Hardware Fault 2021-11-18T23:58:35.444Z,1637279915.444 [CommandExec](FAULT): Scheduling is paused 2021-11-18T23:58:35.444Z,1637279915.444 [CBIT](INFO): Critical error at 20211118T235835 2021-11-18T23:58:35.444Z,1637279915.444 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-11-18T23:58:35.447Z,1637279915.447 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-11-18T23:58:35.448Z,1637279915.448 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-11-18T23:58:36.125Z,1637279916.125 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-11-18T23:58:36.125Z,1637279916.125 [RudderServo](FAULT): Rudder failed to initialize 2021-11-18T23:58:36.125Z,1637279916.125 [RudderServo] Communications Fault, FailCount= 1 2021-11-18T23:58:36.125Z,1637279916.125 [RudderServo](ERROR): Communications Fault 2021-11-18T23:58:36.226Z,1637279916.226 [CBIT](INFO): Critical error at 20211118T235835 2021-11-18T23:58:36.229Z,1637279916.229 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-11-18T23:58:36.442Z,1637279916.442 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-11-18T23:58:36.442Z,1637279916.442 [RudderServo](INFO): Powering down 2021-11-18T23:58:36.781Z,1637279916.781 [WetLabsBB2FL](INFO): Powering down 2021-11-18T23:58:37.067Z,1637279917.067 [RudderServo](DEBUG): Initializing EZServoServo. 2021-11-18T23:58:37.186Z,1637279917.186 [RudderServo](DEBUG): Initializing RudderServo. 2021-11-18T23:58:37.191Z,1637279917.191 [CBIT](INFO): Clearing failed state for component RudderServo 2021-11-18T23:58:37.191Z,1637279917.191 [RudderServo] No Fault, FailCount= 1 2021-11-18T23:58:41.698Z,1637279921.698 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-11-18T23:58:46.755Z,1637279926.755 [DAT](INFO): DAT read: 2021-11-18T23:58:46.756Z,1637279926.756 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-18T23:58:48.367Z,1637279928.367 [DAT](INFO): DAT read: MF Frequency Band 2021-11-18T23:58:48.368Z,1637279928.368 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-18T23:58:48.369Z,1637279928.369 [DAT](INFO): DAT read: Nov 18 2021 23:58:08 2021-11-18T23:58:49.579Z,1637279929.579 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-18T23:58:49.580Z,1637279929.580 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-18T23:58:49.581Z,1637279929.581 [DAT](INFO): commRate: 1200 2021-11-18T23:58:51.599Z,1637279931.599 [DAT](INFO): entering command mode 2021-11-18T23:58:52.003Z,1637279932.003 [DAT](INFO): DAT read: 2021-11-18T23:58:52.004Z,1637279932.004 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:52.407Z,1637279932.407 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:52.811Z,1637279932.811 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:53.215Z,1637279933.215 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:53.619Z,1637279933.619 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:54.023Z,1637279934.023 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:54.427Z,1637279934.427 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:54.831Z,1637279934.831 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:55.235Z,1637279935.235 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:55.639Z,1637279935.639 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:56.049Z,1637279936.049 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:56.447Z,1637279936.447 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:56.852Z,1637279936.852 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:57.255Z,1637279937.255 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:57.663Z,1637279937.663 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:58.063Z,1637279938.063 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:58.467Z,1637279938.467 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:58.871Z,1637279938.871 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:59.281Z,1637279939.281 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:58:59.679Z,1637279939.679 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:00.090Z,1637279940.090 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:00.487Z,1637279940.487 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:00.891Z,1637279940.891 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:01.295Z,1637279941.295 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:01.703Z,1637279941.703 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:02.103Z,1637279942.103 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:02.507Z,1637279942.507 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:02.911Z,1637279942.911 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:03.319Z,1637279943.319 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:03.719Z,1637279943.719 [NAL9602](INFO): Powering up NAL9602 2021-11-18T23:59:03.720Z,1637279943.720 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:04.123Z,1637279944.123 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:04.527Z,1637279944.527 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:04.931Z,1637279944.931 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:05.335Z,1637279945.335 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:05.743Z,1637279945.743 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:06.143Z,1637279946.143 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:06.547Z,1637279946.547 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:06.969Z,1637279946.969 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-18T23:59:06.969Z,1637279946.969 [DAT](FAULT): failed to enter command mode 2021-11-18T23:59:07.355Z,1637279947.355 [DAT](INFO): entering command mode 2021-11-18T23:59:07.759Z,1637279947.759 [DAT](INFO): DAT read: user:1> 2021-11-18T23:59:07.760Z,1637279947.760 [DAT](INFO): DAT read: Command '+++' not found 2021-11-18T23:59:07.760Z,1637279947.760 [DAT](INFO): DAT read: Error 2021-11-18T23:59:07.761Z,1637279947.761 [DAT](INFO): setting verbose to 3 2021-11-18T23:59:08.163Z,1637279948.163 [DAT](INFO): DAT read: user:2> 2021-11-18T23:59:08.165Z,1637279948.165 [DAT](INFO): DAT read: Verbose | 3 2021-11-18T23:59:08.186Z,1637279948.186 [DAT](INFO): set verbose to 3 2021-11-18T23:59:08.187Z,1637279948.187 [DAT](INFO): setting DatVerbose to 27440 2021-11-18T23:59:08.567Z,1637279948.567 [DAT](INFO): DAT read: user:3> 2021-11-18T23:59:08.568Z,1637279948.568 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-18T23:59:08.568Z,1637279948.568 [DAT](INFO): set DatVerbose to 27440 2021-11-18T23:59:08.569Z,1637279948.569 [DAT](INFO): setting transmit power to 8 2021-11-18T23:59:08.971Z,1637279948.971 [DAT](INFO): DAT read: user:4> 2021-11-18T23:59:08.972Z,1637279948.972 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-18T23:59:08.972Z,1637279948.972 [DAT](INFO): set transmit power to 8 2021-11-18T23:59:08.973Z,1637279948.973 [DAT](INFO): setting local address to 7 2021-11-18T23:59:09.379Z,1637279949.379 [DAT](INFO): DAT read: user:5> 2021-11-18T23:59:09.380Z,1637279949.380 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-18T23:59:09.381Z,1637279949.381 [DAT](INFO): set local address to 7 2021-11-18T23:59:14.626Z,1637279954.626 [NAL9602](INFO): NAL9602 initialized 2021-11-18T23:59:59.086Z,1637279999.086 [SBIT](IMPORTANT): Beginning Startup BIT 2021-11-18T23:59:59.110Z,1637279999.110 [CBIT](IMPORTANT): Beginning ground fault scan 2021-11-19T00:00:10.016Z,1637280010.016 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2021-11-19T00:00:10.434Z,1637280010.434 [CBIT](IMPORTANT): Beginning ground fault scan 2021-11-19T00:00:21.388Z,1637280021.388 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002328 CHAN A1 (24V): 0.000380 CHAN A2 (12V): -0.001808 CHAN A3 (5V): -0.001506 CHAN B0 (3.3V): 0.000222 CHAN B1 (3.15aV): -0.000055 CHAN B2 (3.15bV): -0.000513 CHAN B3 (GND): -0.000289 OPEN: -0.000174 Full Scale: +/- 1 mA 2021-11-19T00:00:53.314Z,1637280053.314 [SBIT](IMPORTANT): SBIT PASSED 2021-11-19T00:00:53.314Z,1637280053.314 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-11-19T00:00:53.315Z,1637280053.315 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2021-11-19T00:00:53.315Z,1637280053.315 [SBIT](IMPORTANT): BuoyancyServo.fastPumpDepth=6 meter; 2021-11-19T00:00:53.315Z,1637280053.315 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2021-11-19T00:00:53.315Z,1637280053.315 [SBIT](IMPORTANT): DAT.baud=9600 bit_per_second; 2021-11-19T00:00:53.315Z,1637280053.315 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): DAT.sbdAddress=1 enum; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): DAT.surfaceThreshold=0 meter; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): DAT.verbosity=3 count; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): DataOverHttps.loadAtStartup=0 bool; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=0 bool; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2021-11-19T00:00:53.316Z,1637280053.316 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=296 cubic_centimeter; 2021-11-19T00:00:53.317Z,1637280053.317 [SBIT](IMPORTANT): VerticalControl.massDefault=-16 millimeter; 2021-11-19T00:00:53.317Z,1637280053.317 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=2 meter; 2021-11-19T00:00:53.655Z,1637280053.655 [MissionManager](IMPORTANT): Started mission Startup 2021-11-19T00:00:53.656Z,1637280053.656 [Startup] Running Loop=1 2021-11-19T00:00:53.656Z,1637280053.656 [Startup](DEBUG): Aggregate::initialize Startup 2021-11-19T00:00:53.656Z,1637280053.656 [Startup:A.GoToSurface] Running Loop=1 2021-11-19T00:00:53.656Z,1637280053.656 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-19T00:00:53.661Z,1637280053.661 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-19T00:00:53.662Z,1637280053.662 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-19T00:00:53.662Z,1637280053.662 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-19T00:00:53.662Z,1637280053.662 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-19T00:00:53.663Z,1637280053.663 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-19T00:00:53.664Z,1637280053.664 [Startup:StartupSatComms] Running Loop=1 2021-11-19T00:00:53.664Z,1637280053.664 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-11-19T00:00:53.664Z,1637280053.664 [Startup:StartupSatComms:A] Running Loop=1 2021-11-19T00:00:54.020Z,1637280054.020 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-11-19T00:01:34.862Z,1637280094.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-11-19T00:01:34.862Z,1637280094.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-19T00:01:34.901Z,1637280094.901 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-11-19T00:01:35.264Z,1637280095.264 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-11-19T00:01:35.264Z,1637280095.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-11-19T00:01:53.816Z,1637280113.816 [Startup:StartupSatComms:A](INFO): Timed out from 2021-11-19T00:00:53.7Z 2021-11-19T00:01:53.816Z,1637280113.816 [Startup:StartupSatComms:A] Stopped 2021-11-19T00:01:53.816Z,1637280113.816 [Startup:StartupSatComms:B] Running Loop=1 2021-11-19T00:01:54.223Z,1637280114.223 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-19T00:01:55.156Z,1637280115.156 [DAT](INFO): #Outgoing data=1 2021-11-19T00:01:55.157Z,1637280115.157 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:01:55.262Z,1637280115.262 [DAT](INFO): setting remote address to 1 2021-11-19T00:01:55.674Z,1637280115.674 [DAT](INFO): DAT read: user:6> 2021-11-19T00:01:55.675Z,1637280115.675 [DAT](INFO): DAT read: RemoteAddr | 1 2021-11-19T00:01:55.675Z,1637280115.675 [DAT](INFO): set remote address to 1 2021-11-19T00:01:55.676Z,1637280115.676 [DAT](INFO): entering online mode 2021-11-19T00:01:56.014Z,1637280116.014 [DAT](INFO): DAT read: user:7> 2021-11-19T00:01:56.014Z,1637280116.014 [DAT](INFO): DAT read: 2021-11-19T00:01:56.016Z,1637280116.016 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:01:56.016Z,1637280116.016 [DAT](INFO): commRate: 1200 2021-11-19T00:01:56.016Z,1637280116.016 [DAT](INFO): online mode acknowledged 2021-11-19T00:01:56.029Z,1637280116.029 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:01:59.248Z,1637280119.248 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:01:22.1689 2021-11-19T00:01:59.248Z,1637280119.248 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:02:09.336Z,1637280129.336 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:02:09.336Z,1637280129.336 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:02:10.281Z,1637280130.281 [DAT](INFO): #Outgoing data=1 2021-11-19T00:02:10.281Z,1637280130.281 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:02:10.377Z,1637280130.377 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:02:13.952Z,1637280133.952 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:01:36.5187 2021-11-19T00:02:13.952Z,1637280133.952 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:02:24.051Z,1637280144.051 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:02:24.051Z,1637280144.051 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:02:24.993Z,1637280144.993 [DAT](INFO): #Outgoing data=2 2021-11-19T00:02:24.994Z,1637280144.994 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:02:25.119Z,1637280145.119 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:02:28.663Z,1637280148.663 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:01:51.2685 2021-11-19T00:02:28.663Z,1637280148.663 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:02:38.772Z,1637280158.772 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:02:38.772Z,1637280158.772 [DAT](DEBUG): In sendingAckWaiting, timeout so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:02:39.686Z,1637280159.686 [DAT](INFO): #Outgoing data=3 2021-11-19T00:02:39.686Z,1637280159.686 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:02:39.781Z,1637280159.781 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:02:43.372Z,1637280163.372 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:02:05.9183 2021-11-19T00:02:43.374Z,1637280163.374 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:02:53.478Z,1637280173.478 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:02:53.478Z,1637280173.478 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:02:53.878Z,1637280173.878 [DAT](INFO): Sent 50 bytes from file Logs/20211118T212911/Courier0043.lzma.parts 2021-11-19T00:02:53.878Z,1637280173.878 [DAT](INFO): Packets left to send: 0 2021-11-19T00:02:53.881Z,1637280173.881 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:02:53.890Z,1637280173.890 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-19T00:01:53.8Z 2021-11-19T00:02:53.890Z,1637280173.890 [Startup:StartupSatComms:B] Stopped 2021-11-19T00:02:53.890Z,1637280173.890 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-11-19T00:02:53.890Z,1637280173.890 [Startup:StartupSatComms] Stopped 2021-11-19T00:02:53.891Z,1637280173.891 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-11-19T00:02:53.891Z,1637280173.891 [Startup](INFO): Completed Startup 2021-11-19T00:02:53.892Z,1637280173.892 [MissionManager](INFO): Startup is completed. 2021-11-19T00:02:53.892Z,1637280173.892 [MissionManager](INFO): Uninitializing Mission Startup 2021-11-19T00:02:53.892Z,1637280173.892 [Startup] Stopped 2021-11-19T00:02:53.892Z,1637280173.892 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-11-19T00:02:53.892Z,1637280173.892 [Startup:A.GoToSurface] Stopped 2021-11-19T00:02:53.892Z,1637280173.892 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-11-19T00:02:54.272Z,1637280174.272 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:02:54.304Z,1637280174.304 [MissionManager](IMPORTANT): Started mission Default 2021-11-19T00:02:54.304Z,1637280174.304 [Default] Running Loop=1 2021-11-19T00:02:54.304Z,1637280174.304 [Default](DEBUG): Aggregate::initialize Default 2021-11-19T00:02:54.304Z,1637280174.304 [Default:B.GoToSurface] Running Loop=1 2021-11-19T00:02:54.304Z,1637280174.304 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-19T00:02:54.305Z,1637280174.305 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-19T00:02:54.305Z,1637280174.305 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-19T00:02:54.306Z,1637280174.306 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-19T00:02:54.306Z,1637280174.306 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-19T00:02:54.306Z,1637280174.306 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-19T00:02:54.307Z,1637280174.307 [Default:A.Wait] Running Loop=1 2021-11-19T00:02:54.307Z,1637280174.307 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-11-19T00:02:54.676Z,1637280174.676 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:02:57.919Z,1637280177.919 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:02:20.8180 2021-11-19T00:02:57.919Z,1637280177.919 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:03:07.616Z,1637280187.616 [Default:A.Wait](INFO): Done Waiting. 2021-11-19T00:03:07.616Z,1637280187.616 [Default:A.Wait] Stopped 2021-11-19T00:03:07.616Z,1637280187.616 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-11-19T00:03:08.030Z,1637280188.030 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:03:08.030Z,1637280188.030 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:03:08.045Z,1637280188.045 [Default:CheckIn] Running Loop=1 2021-11-19T00:03:08.045Z,1637280188.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-11-19T00:03:08.045Z,1637280188.045 [Default:CheckIn:Read_GPS] Running Loop=1 2021-11-19T00:03:08.433Z,1637280188.433 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:03:08.448Z,1637280188.448 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-11-19T00:03:08.834Z,1637280188.834 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:03:09.231Z,1637280189.231 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:03:12.456Z,1637280192.456 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:02:35.3678 2021-11-19T00:03:12.456Z,1637280192.456 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:03:22.552Z,1637280202.552 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:03:22.552Z,1637280202.552 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:03:22.961Z,1637280202.961 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:03:23.363Z,1637280203.363 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:03:23.778Z,1637280203.778 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:03:27.004Z,1637280207.004 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:02:49.9176 2021-11-19T00:03:27.004Z,1637280207.004 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:03:37.099Z,1637280217.099 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:03:37.100Z,1637280217.100 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:03:37.500Z,1637280217.500 [DAT](DEBUG): In sendingVerified, sbd done so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:04:08.216Z,1637280248.216 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-11-19T00:03:08.0Z 2021-11-19T00:04:08.216Z,1637280248.216 [Default:CheckIn:Read_GPS] Stopped 2021-11-19T00:04:08.216Z,1637280248.216 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-11-19T00:04:08.620Z,1637280248.620 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-19T00:04:09.528Z,1637280249.528 [DAT](INFO): #Outgoing data=1 2021-11-19T00:04:09.528Z,1637280249.528 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:04:09.624Z,1637280249.624 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:04:13.200Z,1637280253.200 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:03:35.7669 2021-11-19T00:04:13.200Z,1637280253.200 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:04:18.046Z,1637280258.046 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-11-19T00:04:23.360Z,1637280263.360 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:04:23.360Z,1637280263.360 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:04:23.722Z,1637280263.722 [DAT](INFO): Sent 39 bytes from file Logs/20211118T212911/Courier0046.lzma.parts 2021-11-19T00:04:23.722Z,1637280263.722 [DAT](INFO): Packets left to send: 0 2021-11-19T00:04:23.725Z,1637280263.725 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:04:24.732Z,1637280264.732 [DAT](INFO): #Outgoing data=1 2021-11-19T00:04:24.733Z,1637280264.733 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:04:24.870Z,1637280264.870 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:04:28.428Z,1637280268.428 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:03:51.2167 2021-11-19T00:04:28.428Z,1637280268.428 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:04:35.702Z,1637280275.702 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-11-19T00:04:35.702Z,1637280275.702 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-19T00:04:35.738Z,1637280275.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-11-19T00:04:36.116Z,1637280276.116 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-11-19T00:04:36.116Z,1637280276.116 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-11-19T00:04:38.542Z,1637280278.542 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:04:38.542Z,1637280278.542 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:04:38.947Z,1637280278.947 [DAT](INFO): Sent 241 bytes from file Logs/20211118T235825/Courier0000.lzma.parts 2021-11-19T00:04:38.947Z,1637280278.947 [DAT](INFO): Packets left to send: 0 2021-11-19T00:04:38.950Z,1637280278.950 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:04:39.863Z,1637280279.863 [DAT](INFO): #Outgoing data=1 2021-11-19T00:04:39.863Z,1637280279.863 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:04:39.952Z,1637280279.952 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:04:43.549Z,1637280283.549 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:04:06.1166 2021-11-19T00:04:43.549Z,1637280283.549 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:04:51.699Z,1637280291.699 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 2021-11-19T00:04:51.773Z,1637280291.773 [NAL9602](ERROR): received: +CSQ:0 OK 2021-11-19T00:04:53.658Z,1637280293.658 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:04:53.658Z,1637280293.658 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:04:54.051Z,1637280294.051 [DAT](INFO): Sent 39 bytes from file Logs/20211118T235825/Courier0004.lzma.parts 2021-11-19T00:04:54.052Z,1637280294.052 [DAT](INFO): Packets left to send: 0 2021-11-19T00:04:54.054Z,1637280294.054 [DAT](DEBUG): In sendingVerified, sbd waiting so set commsState_ = SENDING_FILL_BUFFER 2021-11-19T00:04:54.964Z,1637280294.964 [DAT](INFO): #Outgoing data=1 2021-11-19T00:04:54.965Z,1637280294.965 [DAT](DEBUG): In sendingFillBuffer, set commsState_ = SENDING_TRANSMIT 2021-11-19T00:04:55.083Z,1637280295.083 [DAT](DEBUG): In sendingTransmit, set commsState_ = SENDING_TRANSMIT_VERIFY 2021-11-19T00:04:59.058Z,1637280299.058 [DAT](INFO): DAT read: Forwarding Delay UpTx time:00:04:21.7163 2021-11-19T00:04:59.059Z,1637280299.059 [DAT](DEBUG): In parseResponses, set commsState_ = SENDING_ACK_WAITING 2021-11-19T00:05:09.666Z,1637280309.666 [DAT](FAULT): Ack receipt timeout failure. 2021-11-19T00:05:09.666Z,1637280309.666 [DAT](DEBUG): In sendingAckWaiting, hit max timeouts so set commsState_ = SENDING_VERIFIED 2021-11-19T00:05:09.878Z,1637280309.878 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:05:09.878Z,1637280309.878 [DAT] Software Fault, FailCount= 1 2021-11-19T00:05:09.878Z,1637280309.878 [DAT](ERROR): Software Fault 2021-11-19T00:05:09.881Z,1637280309.881 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:05:09.881Z,1637280309.881 [SyncHandler](INFO): Protected caller Thread ID is 1207 2021-11-19T00:05:09.919Z,1637280309.919 [CBIT](INFO): Critical error at 20211119T000509 2021-11-19T00:05:09.922Z,1637280309.922 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:05:10.126Z,1637280310.126 [DAT](INFO): Powering down 2021-11-19T00:05:10.962Z,1637280310.962 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:05:10.962Z,1637280310.962 [DAT] No Fault, FailCount= 1 2021-11-19T00:05:13.361Z,1637280313.361 [DAT](INFO): Powering up 2021-11-19T00:05:13.362Z,1637280313.362 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:05:24.802Z,1637280324.802 [DAT](INFO): DAT read: 2021-11-19T00:05:25.206Z,1637280325.206 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:05:26.531Z,1637280326.531 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:05:26.532Z,1637280326.532 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:05:26.533Z,1637280326.533 [DAT](INFO): DAT read: Nov 19 2021 00:04:46 2021-11-19T00:05:27.803Z,1637280327.803 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:05:27.804Z,1637280327.804 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:05:27.804Z,1637280327.804 [DAT](INFO): commRate: 1200 2021-11-19T00:05:30.375Z,1637280330.375 [DAT](INFO): entering command mode 2021-11-19T00:05:30.471Z,1637280330.471 [DAT](INFO): DAT read: 2021-11-19T00:05:30.472Z,1637280330.472 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:30.902Z,1637280330.902 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:31.246Z,1637280331.246 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:31.658Z,1637280331.658 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:32.053Z,1637280332.053 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:32.503Z,1637280332.503 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:32.886Z,1637280332.886 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:33.266Z,1637280333.266 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:33.682Z,1637280333.682 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:34.582Z,1637280334.582 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:34.671Z,1637280334.671 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:35.062Z,1637280335.062 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:35.470Z,1637280335.470 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:35.866Z,1637280335.866 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:36.255Z,1637280336.255 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:36.660Z,1637280336.660 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:37.090Z,1637280337.090 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:37.482Z,1637280337.482 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:38.710Z,1637280338.710 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:38.795Z,1637280338.795 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:39.166Z,1637280339.166 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:39.582Z,1637280339.582 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:39.982Z,1637280339.982 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:40.368Z,1637280340.368 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:40.775Z,1637280340.775 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:41.183Z,1637280341.183 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:41.581Z,1637280341.581 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:42.010Z,1637280342.010 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:42.406Z,1637280342.406 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:43.453Z,1637280343.453 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:43.537Z,1637280343.537 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:43.906Z,1637280343.906 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:44.374Z,1637280344.374 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:44.738Z,1637280344.738 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:45.119Z,1637280345.119 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:45.584Z,1637280345.584 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:05:45.584Z,1637280345.584 [DAT](FAULT): failed to enter command mode 2021-11-19T00:05:45.946Z,1637280345.946 [DAT](INFO): entering command mode 2021-11-19T00:05:46.331Z,1637280346.331 [DAT](INFO): DAT read: user:1> 2021-11-19T00:05:46.332Z,1637280346.332 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:05:46.333Z,1637280346.333 [DAT](INFO): DAT read: Error 2021-11-19T00:05:46.333Z,1637280346.333 [DAT](INFO): setting verbose to 3 2021-11-19T00:05:46.754Z,1637280346.754 [DAT](INFO): DAT read: user:2> 2021-11-19T00:05:46.755Z,1637280346.755 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:05:46.755Z,1637280346.755 [DAT](INFO): set verbose to 3 2021-11-19T00:05:46.755Z,1637280346.755 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:05:47.646Z,1637280347.646 [DAT](INFO): DAT read: user:3> 2021-11-19T00:05:47.647Z,1637280347.647 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:05:47.647Z,1637280347.647 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:05:47.648Z,1637280347.648 [DAT](INFO): setting transmit power to 8 2021-11-19T00:05:47.744Z,1637280347.744 [DAT](INFO): DAT read: user:4> 2021-11-19T00:05:47.745Z,1637280347.745 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:05:47.746Z,1637280347.746 [DAT](INFO): set transmit power to 8 2021-11-19T00:05:47.746Z,1637280347.746 [DAT](INFO): setting local address to 7 2021-11-19T00:05:48.150Z,1637280348.150 [DAT](INFO): DAT read: user:5> 2021-11-19T00:05:48.151Z,1637280348.151 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:05:48.151Z,1637280348.151 [DAT](INFO): set local address to 7 2021-11-19T00:05:48.229Z,1637280348.229 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:05:48.229Z,1637280348.229 [DAT] Software Fault, FailCount= 1 2021-11-19T00:05:48.229Z,1637280348.229 [DAT](ERROR): Software Fault 2021-11-19T00:05:48.231Z,1637280348.231 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:05:48.231Z,1637280348.231 [SyncHandler](INFO): Protected caller Thread ID is 1209 2021-11-19T00:05:48.267Z,1637280348.267 [CBIT](INFO): Critical error at 20211119T000548 2021-11-19T00:05:48.270Z,1637280348.270 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:05:48.553Z,1637280348.553 [DAT](INFO): Powering down 2021-11-19T00:05:49.367Z,1637280349.367 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:05:49.367Z,1637280349.367 [DAT] No Fault, FailCount= 1 2021-11-19T00:05:52.302Z,1637280352.302 [DAT](INFO): Powering up 2021-11-19T00:05:52.302Z,1637280352.302 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:05:58.578Z,1637280358.578 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-11-19T00:05:58.582Z,1637280358.582 [BPC1](INFO): Received data from all battery sticks. 2021-11-19T00:06:04.530Z,1637280364.530 [DAT](INFO): DAT read: 2021-11-19T00:06:04.531Z,1637280364.531 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:06:05.410Z,1637280365.410 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:06:05.807Z,1637280365.807 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:06:05.807Z,1637280365.807 [DAT](INFO): DAT read: Nov 19 2021 00:05:25 2021-11-19T00:06:06.609Z,1637280366.609 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:06:06.610Z,1637280366.610 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:06:06.610Z,1637280366.610 [DAT](INFO): commRate: 1200 2021-11-19T00:06:08.783Z,1637280368.783 [DAT](INFO): entering command mode 2021-11-19T00:06:09.194Z,1637280369.194 [DAT](INFO): DAT read: 2021-11-19T00:06:09.194Z,1637280369.194 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:09.590Z,1637280369.590 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:09.980Z,1637280369.980 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:10.385Z,1637280370.385 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:10.814Z,1637280370.814 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:11.192Z,1637280371.192 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:11.618Z,1637280371.618 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:12.514Z,1637280372.514 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:12.607Z,1637280372.607 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:12.969Z,1637280372.969 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:13.374Z,1637280373.374 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:13.774Z,1637280373.774 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:14.171Z,1637280374.171 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:14.572Z,1637280374.572 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:14.998Z,1637280374.998 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:15.390Z,1637280375.390 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:16.322Z,1637280376.322 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:16.403Z,1637280376.403 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:16.790Z,1637280376.790 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:17.202Z,1637280377.202 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:17.610Z,1637280377.610 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:17.995Z,1637280377.995 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:18.397Z,1637280378.397 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:18.826Z,1637280378.826 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:19.209Z,1637280379.209 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:19.627Z,1637280379.627 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:20.562Z,1637280380.562 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:20.651Z,1637280380.651 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:21.018Z,1637280381.018 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:21.422Z,1637280381.422 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:21.826Z,1637280381.826 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:22.216Z,1637280382.216 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:22.624Z,1637280382.624 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:23.059Z,1637280383.059 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:23.442Z,1637280383.442 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:24.350Z,1637280384.350 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:24.350Z,1637280384.350 [DAT](FAULT): failed to enter command mode 2021-11-19T00:06:24.432Z,1637280384.432 [DAT](INFO): entering command mode 2021-11-19T00:06:24.814Z,1637280384.814 [DAT](INFO): DAT read: user:1> 2021-11-19T00:06:24.815Z,1637280384.815 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:06:24.816Z,1637280384.816 [DAT](INFO): DAT read: Error 2021-11-19T00:06:24.816Z,1637280384.816 [DAT](INFO): setting verbose to 3 2021-11-19T00:06:25.218Z,1637280385.218 [DAT](INFO): DAT read: user:2> 2021-11-19T00:06:25.219Z,1637280385.219 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:06:25.219Z,1637280385.219 [DAT](INFO): set verbose to 3 2021-11-19T00:06:25.219Z,1637280385.219 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:06:25.626Z,1637280385.626 [DAT](INFO): DAT read: user:3> 2021-11-19T00:06:25.627Z,1637280385.627 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:06:25.628Z,1637280385.628 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:06:25.628Z,1637280385.628 [DAT](INFO): setting transmit power to 8 2021-11-19T00:06:26.009Z,1637280386.009 [DAT](INFO): DAT read: user:4> 2021-11-19T00:06:26.010Z,1637280386.010 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:06:26.011Z,1637280386.011 [DAT](INFO): set transmit power to 8 2021-11-19T00:06:26.011Z,1637280386.011 [DAT](INFO): setting local address to 7 2021-11-19T00:06:26.417Z,1637280386.417 [DAT](INFO): DAT read: user:5> 2021-11-19T00:06:26.418Z,1637280386.418 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:06:26.418Z,1637280386.418 [DAT](INFO): set local address to 7 2021-11-19T00:06:26.476Z,1637280386.476 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:06:26.476Z,1637280386.476 [DAT] Software Fault, FailCount= 1 2021-11-19T00:06:26.476Z,1637280386.476 [DAT](ERROR): Software Fault 2021-11-19T00:06:26.491Z,1637280386.491 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:06:26.491Z,1637280386.491 [SyncHandler](INFO): Protected caller Thread ID is 1211 2021-11-19T00:06:26.540Z,1637280386.540 [CBIT](INFO): Critical error at 20211119T000626 2021-11-19T00:06:26.543Z,1637280386.543 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:06:26.849Z,1637280386.849 [DAT](INFO): Powering down 2021-11-19T00:06:27.678Z,1637280387.678 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:06:27.678Z,1637280387.678 [DAT] No Fault, FailCount= 1 2021-11-19T00:06:30.199Z,1637280390.199 [DAT](INFO): Powering up 2021-11-19T00:06:30.199Z,1637280390.199 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:06:41.630Z,1637280401.630 [DAT](INFO): DAT read: 2021-11-19T00:06:42.028Z,1637280402.028 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:06:43.655Z,1637280403.655 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:06:43.656Z,1637280403.656 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:06:43.656Z,1637280403.656 [DAT](INFO): DAT read: Nov 19 2021 00:06:03 2021-11-19T00:06:44.566Z,1637280404.566 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:06:44.568Z,1637280404.568 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:06:44.568Z,1637280404.568 [DAT](INFO): commRate: 1200 2021-11-19T00:06:46.641Z,1637280406.641 [DAT](INFO): entering command mode 2021-11-19T00:06:47.075Z,1637280407.075 [DAT](INFO): DAT read: 2021-11-19T00:06:47.075Z,1637280407.075 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:47.462Z,1637280407.462 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:48.406Z,1637280408.406 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:48.499Z,1637280408.499 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:48.882Z,1637280408.882 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:49.290Z,1637280409.290 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:49.694Z,1637280409.694 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:50.083Z,1637280410.083 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:50.489Z,1637280410.489 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:50.914Z,1637280410.914 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:51.295Z,1637280411.295 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:51.710Z,1637280411.710 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:52.718Z,1637280412.718 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:52.798Z,1637280412.798 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:53.174Z,1637280413.174 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:53.578Z,1637280413.578 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:53.970Z,1637280413.970 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:54.367Z,1637280414.367 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:54.777Z,1637280414.777 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:55.170Z,1637280415.170 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:55.577Z,1637280415.577 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:56.002Z,1637280416.002 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:56.402Z,1637280416.402 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:57.382Z,1637280417.382 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:57.464Z,1637280417.464 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:57.830Z,1637280417.830 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:58.242Z,1637280418.242 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:58.642Z,1637280418.642 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:59.029Z,1637280419.029 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:59.440Z,1637280419.440 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:06:59.866Z,1637280419.866 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:00.244Z,1637280420.244 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:00.662Z,1637280420.662 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:01.566Z,1637280421.566 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:01.649Z,1637280421.649 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:01.649Z,1637280421.649 [DAT](FAULT): failed to enter command mode 2021-11-19T00:07:02.026Z,1637280422.026 [DAT](INFO): entering command mode 2021-11-19T00:07:02.431Z,1637280422.431 [DAT](INFO): DAT read: user:1> 2021-11-19T00:07:02.432Z,1637280422.432 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:07:02.432Z,1637280422.432 [DAT](INFO): DAT read: Error 2021-11-19T00:07:02.433Z,1637280422.433 [DAT](INFO): setting verbose to 3 2021-11-19T00:07:02.822Z,1637280422.822 [DAT](INFO): DAT read: user:2> 2021-11-19T00:07:02.823Z,1637280422.823 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:07:02.823Z,1637280422.823 [DAT](INFO): set verbose to 3 2021-11-19T00:07:02.824Z,1637280422.824 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:07:03.219Z,1637280423.219 [DAT](INFO): DAT read: user:3> 2021-11-19T00:07:03.220Z,1637280423.220 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:07:03.221Z,1637280423.221 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:07:03.221Z,1637280423.221 [DAT](INFO): setting transmit power to 8 2021-11-19T00:07:03.629Z,1637280423.629 [DAT](INFO): DAT read: user:4> 2021-11-19T00:07:03.630Z,1637280423.630 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:07:03.630Z,1637280423.630 [DAT](INFO): set transmit power to 8 2021-11-19T00:07:03.630Z,1637280423.630 [DAT](INFO): setting local address to 7 2021-11-19T00:07:04.054Z,1637280424.054 [DAT](INFO): DAT read: user:5> 2021-11-19T00:07:04.055Z,1637280424.055 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:07:04.055Z,1637280424.055 [DAT](INFO): set local address to 7 2021-11-19T00:07:04.150Z,1637280424.150 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:07:04.150Z,1637280424.150 [DAT] Software Fault, FailCount= 1 2021-11-19T00:07:04.150Z,1637280424.150 [DAT](ERROR): Software Fault 2021-11-19T00:07:04.151Z,1637280424.151 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:07:04.151Z,1637280424.151 [SyncHandler](INFO): Protected caller Thread ID is 1213 2021-11-19T00:07:04.175Z,1637280424.175 [CBIT](INFO): Critical error at 20211119T000704 2021-11-19T00:07:04.178Z,1637280424.178 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:07:04.441Z,1637280424.441 [DAT](INFO): Powering down 2021-11-19T00:07:05.508Z,1637280425.508 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:07:05.509Z,1637280425.509 [DAT] No Fault, FailCount= 1 2021-11-19T00:07:07.865Z,1637280427.865 [DAT](INFO): Powering up 2021-11-19T00:07:07.866Z,1637280427.866 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:07:19.624Z,1637280439.624 [DAT](INFO): DAT read: 2021-11-19T00:07:19.625Z,1637280439.625 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:07:21.366Z,1637280441.366 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:07:21.368Z,1637280441.368 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:07:21.368Z,1637280441.368 [DAT](INFO): DAT read: Nov 19 2021 00:06:41 2021-11-19T00:07:22.219Z,1637280442.219 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:07:22.220Z,1637280442.220 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:07:22.220Z,1637280442.220 [DAT](INFO): commRate: 1200 2021-11-19T00:07:24.235Z,1637280444.235 [DAT](INFO): entering command mode 2021-11-19T00:07:24.647Z,1637280444.647 [DAT](INFO): DAT read: 2021-11-19T00:07:24.648Z,1637280444.648 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:25.582Z,1637280445.582 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:25.667Z,1637280445.667 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:26.034Z,1637280446.034 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:26.442Z,1637280446.442 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:26.850Z,1637280446.850 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:27.235Z,1637280447.235 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:27.640Z,1637280447.640 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:28.066Z,1637280448.066 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:28.462Z,1637280448.462 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:29.370Z,1637280449.370 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:29.455Z,1637280449.455 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:29.822Z,1637280449.822 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:30.230Z,1637280450.230 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:30.631Z,1637280450.631 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:31.019Z,1637280451.019 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:31.429Z,1637280451.429 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:31.858Z,1637280451.858 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:32.236Z,1637280452.236 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:32.654Z,1637280452.654 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:33.558Z,1637280453.558 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:33.644Z,1637280453.644 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:34.006Z,1637280454.006 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:34.414Z,1637280454.414 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:34.818Z,1637280454.818 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:35.213Z,1637280455.213 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:35.608Z,1637280455.608 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:36.046Z,1637280456.046 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:36.434Z,1637280456.434 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:37.346Z,1637280457.346 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:37.352Z,1637280457.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-11-19T00:07:37.353Z,1637280457.353 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-11-19T00:07:37.369Z,1637280457.369 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-11-19T00:07:37.444Z,1637280457.444 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:37.461Z,1637280457.461 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-11-19T00:07:37.462Z,1637280457.462 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-11-19T00:07:37.798Z,1637280457.798 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:38.210Z,1637280458.210 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:38.614Z,1637280458.614 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:39.003Z,1637280459.003 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:39.408Z,1637280459.408 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:07:39.408Z,1637280459.408 [DAT](FAULT): failed to enter command mode 2021-11-19T00:07:39.834Z,1637280459.834 [DAT](INFO): entering command mode 2021-11-19T00:07:40.220Z,1637280460.220 [DAT](INFO): DAT read: user:1> 2021-11-19T00:07:40.221Z,1637280460.221 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:07:40.222Z,1637280460.222 [DAT](INFO): DAT read: Error 2021-11-19T00:07:40.222Z,1637280460.222 [DAT](INFO): setting verbose to 3 2021-11-19T00:07:40.634Z,1637280460.634 [DAT](INFO): DAT read: user:2> 2021-11-19T00:07:40.635Z,1637280460.635 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:07:40.636Z,1637280460.636 [DAT](INFO): set verbose to 3 2021-11-19T00:07:40.636Z,1637280460.636 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:07:41.538Z,1637280461.538 [DAT](INFO): DAT read: user:3> 2021-11-19T00:07:41.539Z,1637280461.539 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:07:41.539Z,1637280461.539 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:07:41.540Z,1637280461.540 [DAT](INFO): setting transmit power to 8 2021-11-19T00:07:41.628Z,1637280461.628 [DAT](INFO): DAT read: user:4> 2021-11-19T00:07:41.630Z,1637280461.630 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:07:41.630Z,1637280461.630 [DAT](INFO): set transmit power to 8 2021-11-19T00:07:41.630Z,1637280461.630 [DAT](INFO): setting local address to 7 2021-11-19T00:07:42.037Z,1637280462.037 [DAT](INFO): DAT read: user:5> 2021-11-19T00:07:42.039Z,1637280462.039 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:07:42.039Z,1637280462.039 [DAT](INFO): set local address to 7 2021-11-19T00:07:42.104Z,1637280462.104 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:07:42.104Z,1637280462.104 [DAT] Software Fault, FailCount= 1 2021-11-19T00:07:42.104Z,1637280462.104 [DAT](ERROR): Software Fault 2021-11-19T00:07:42.106Z,1637280462.106 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:07:42.106Z,1637280462.106 [SyncHandler](INFO): Protected caller Thread ID is 1215 2021-11-19T00:07:42.156Z,1637280462.156 [CBIT](INFO): Critical error at 20211119T000742 2021-11-19T00:07:42.162Z,1637280462.162 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:07:42.441Z,1637280462.441 [DAT](INFO): Powering down 2021-11-19T00:07:43.257Z,1637280463.257 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:07:43.257Z,1637280463.257 [DAT] No Fault, FailCount= 1 2021-11-19T00:07:45.491Z,1637280465.491 [DAT](INFO): Powering up 2021-11-19T00:07:45.491Z,1637280465.491 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:07:57.578Z,1637280477.578 [DAT](INFO): DAT read: 2021-11-19T00:07:57.579Z,1637280477.579 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:07:58.858Z,1637280478.858 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:07:58.860Z,1637280478.860 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:07:58.860Z,1637280478.860 [DAT](INFO): DAT read: Nov 19 2021 00:07:18 2021-11-19T00:08:00.078Z,1637280480.078 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:08:00.080Z,1637280480.080 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:08:00.080Z,1637280480.080 [DAT](INFO): commRate: 1200 2021-11-19T00:08:02.279Z,1637280482.279 [DAT](INFO): entering command mode 2021-11-19T00:08:02.674Z,1637280482.674 [DAT](INFO): DAT read: 2021-11-19T00:08:02.674Z,1637280482.674 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:03.067Z,1637280483.067 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:03.473Z,1637280483.473 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:03.902Z,1637280483.902 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:04.279Z,1637280484.279 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:04.698Z,1637280484.698 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:05.638Z,1637280485.638 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:05.719Z,1637280485.719 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:06.106Z,1637280486.106 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:06.518Z,1637280486.518 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:06.906Z,1637280486.906 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:07.305Z,1637280487.305 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:07.730Z,1637280487.730 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:08.118Z,1637280488.118 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:09.040Z,1637280489.040 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:09.127Z,1637280489.127 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:09.494Z,1637280489.494 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:09.902Z,1637280489.902 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:10.314Z,1637280490.314 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:10.689Z,1637280490.689 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:11.099Z,1637280491.099 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:11.501Z,1637280491.501 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:11.926Z,1637280491.926 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:12.326Z,1637280492.326 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:13.226Z,1637280493.226 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:13.312Z,1637280493.312 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:13.678Z,1637280493.678 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:14.096Z,1637280494.096 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:14.482Z,1637280494.482 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:14.883Z,1637280494.883 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:15.280Z,1637280495.280 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:15.683Z,1637280495.683 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:16.114Z,1637280496.114 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:16.514Z,1637280496.514 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:17.434Z,1637280497.434 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:17.435Z,1637280497.435 [DAT](FAULT): failed to enter command mode 2021-11-19T00:08:17.530Z,1637280497.530 [DAT](INFO): entering command mode 2021-11-19T00:08:17.890Z,1637280497.890 [DAT](INFO): DAT read: user:1> 2021-11-19T00:08:17.891Z,1637280497.891 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:08:17.892Z,1637280497.892 [DAT](INFO): DAT read: Error 2021-11-19T00:08:17.892Z,1637280497.892 [DAT](INFO): setting verbose to 3 2021-11-19T00:08:18.302Z,1637280498.302 [DAT](INFO): DAT read: user:2> 2021-11-19T00:08:18.303Z,1637280498.303 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:08:18.303Z,1637280498.303 [DAT](INFO): set verbose to 3 2021-11-19T00:08:18.304Z,1637280498.304 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:08:18.698Z,1637280498.698 [DAT](INFO): DAT read: user:3> 2021-11-19T00:08:18.699Z,1637280498.699 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:08:18.699Z,1637280498.699 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:08:18.700Z,1637280498.700 [DAT](INFO): setting transmit power to 8 2021-11-19T00:08:19.085Z,1637280499.085 [DAT](INFO): DAT read: user:4> 2021-11-19T00:08:19.086Z,1637280499.086 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:08:19.087Z,1637280499.087 [DAT](INFO): set transmit power to 8 2021-11-19T00:08:19.087Z,1637280499.087 [DAT](INFO): setting local address to 7 2021-11-19T00:08:19.497Z,1637280499.497 [DAT](INFO): DAT read: user:5> 2021-11-19T00:08:19.498Z,1637280499.498 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:08:19.498Z,1637280499.498 [DAT](INFO): set local address to 7 2021-11-19T00:08:19.636Z,1637280499.636 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:08:19.636Z,1637280499.636 [DAT] Software Fault, FailCount= 1 2021-11-19T00:08:19.636Z,1637280499.636 [DAT](ERROR): Software Fault 2021-11-19T00:08:19.638Z,1637280499.638 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:08:19.638Z,1637280499.638 [SyncHandler](INFO): Protected caller Thread ID is 1217 2021-11-19T00:08:19.657Z,1637280499.657 [CBIT](INFO): Critical error at 20211119T000819 2021-11-19T00:08:19.660Z,1637280499.660 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:08:19.917Z,1637280499.917 [DAT](INFO): Powering down 2021-11-19T00:08:20.736Z,1637280500.736 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:08:20.736Z,1637280500.736 [DAT] No Fault, FailCount= 1 2021-11-19T00:08:23.267Z,1637280503.267 [DAT](INFO): Powering up 2021-11-19T00:08:23.268Z,1637280503.268 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:08:34.708Z,1637280514.708 [DAT](INFO): DAT read: 2021-11-19T00:08:35.116Z,1637280515.116 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:08:37.242Z,1637280517.242 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:08:37.244Z,1637280517.244 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:08:37.244Z,1637280517.244 [DAT](INFO): DAT read: Nov 19 2021 00:07:56 2021-11-19T00:08:37.700Z,1637280517.700 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:08:37.702Z,1637280517.702 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:08:37.702Z,1637280517.702 [DAT](INFO): commRate: 1200 2021-11-19T00:08:40.130Z,1637280520.130 [DAT](INFO): entering command mode 2021-11-19T00:08:40.522Z,1637280520.522 [DAT](INFO): DAT read: 2021-11-19T00:08:40.522Z,1637280520.522 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:41.438Z,1637280521.438 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:41.523Z,1637280521.523 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:41.890Z,1637280521.890 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:42.298Z,1637280522.298 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:42.706Z,1637280522.706 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:43.091Z,1637280523.091 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:43.496Z,1637280523.496 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:43.926Z,1637280523.926 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:44.305Z,1637280524.305 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:44.722Z,1637280524.722 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:45.658Z,1637280525.658 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:45.747Z,1637280525.747 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:46.106Z,1637280526.106 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:46.518Z,1637280526.518 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:46.922Z,1637280526.922 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:47.312Z,1637280527.312 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:47.738Z,1637280527.738 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:48.134Z,1637280528.134 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:49.038Z,1637280529.038 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:49.119Z,1637280529.119 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:49.486Z,1637280529.486 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:49.898Z,1637280529.898 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:50.298Z,1637280530.298 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:50.705Z,1637280530.705 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:51.095Z,1637280531.095 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:51.497Z,1637280531.497 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:51.922Z,1637280531.922 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:52.322Z,1637280532.322 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:53.254Z,1637280533.254 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:53.343Z,1637280533.343 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:53.706Z,1637280533.706 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:54.114Z,1637280534.114 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:54.518Z,1637280534.518 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:54.907Z,1637280534.907 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:55.313Z,1637280535.313 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:08:55.313Z,1637280535.313 [DAT](FAULT): failed to enter command mode 2021-11-19T00:08:55.738Z,1637280535.738 [DAT](INFO): entering command mode 2021-11-19T00:08:56.134Z,1637280536.134 [DAT](INFO): DAT read: user:1> 2021-11-19T00:08:56.135Z,1637280536.135 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:08:56.136Z,1637280536.136 [DAT](INFO): DAT read: Error 2021-11-19T00:08:56.136Z,1637280536.136 [DAT](INFO): setting verbose to 3 2021-11-19T00:08:57.034Z,1637280537.034 [DAT](INFO): DAT read: user:2> 2021-11-19T00:08:57.035Z,1637280537.035 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:08:57.036Z,1637280537.036 [DAT](INFO): set verbose to 3 2021-11-19T00:08:57.036Z,1637280537.036 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:08:57.157Z,1637280537.157 [DAT](INFO): DAT read: user:3> 2021-11-19T00:08:57.158Z,1637280537.158 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:08:57.159Z,1637280537.159 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:08:57.159Z,1637280537.159 [DAT](INFO): setting transmit power to 8 2021-11-19T00:08:57.521Z,1637280537.521 [DAT](INFO): DAT read: user:4> 2021-11-19T00:08:57.522Z,1637280537.522 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:08:57.522Z,1637280537.522 [DAT](INFO): set transmit power to 8 2021-11-19T00:08:57.523Z,1637280537.523 [DAT](INFO): setting local address to 7 2021-11-19T00:08:57.922Z,1637280537.922 [DAT](INFO): DAT read: user:5> 2021-11-19T00:08:57.923Z,1637280537.923 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:08:57.923Z,1637280537.923 [DAT](INFO): set local address to 7 2021-11-19T00:08:58.089Z,1637280538.089 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:08:58.090Z,1637280538.090 [DAT] Software Fault, FailCount= 1 2021-11-19T00:08:58.090Z,1637280538.090 [DAT](ERROR): Software Fault 2021-11-19T00:08:58.091Z,1637280538.091 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:08:58.091Z,1637280538.091 [SyncHandler](INFO): Protected caller Thread ID is 1221 2021-11-19T00:08:58.109Z,1637280538.109 [CBIT](INFO): Critical error at 20211119T000858 2021-11-19T00:08:58.112Z,1637280538.112 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:08:58.329Z,1637280538.329 [DAT](INFO): Powering down 2021-11-19T00:08:59.155Z,1637280539.155 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:08:59.155Z,1637280539.155 [DAT] No Fault, FailCount= 1 2021-11-19T00:09:01.344Z,1637280541.344 [DAT](INFO): Powering up 2021-11-19T00:09:01.344Z,1637280541.344 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:09:13.078Z,1637280553.078 [DAT](INFO): DAT read: 2021-11-19T00:09:13.079Z,1637280553.079 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:09:14.732Z,1637280554.732 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:09:14.747Z,1637280554.747 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:09:14.748Z,1637280554.748 [DAT](INFO): DAT read: Nov 19 2021 00:08:34 2021-11-19T00:09:15.542Z,1637280555.542 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:09:15.970Z,1637280555.970 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:09:15.971Z,1637280555.971 [DAT](INFO): commRate: 1200 2021-11-19T00:09:17.726Z,1637280557.726 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-11-19T00:09:17.726Z,1637280557.726 [NAL9602] Data Fault, FailCount= 1 2021-11-19T00:09:17.727Z,1637280557.727 [NAL9602](ERROR): Data Fault 2021-11-19T00:09:17.746Z,1637280557.746 [CBIT](ERROR): Data Fault in component: NAL9602 2021-11-19T00:09:18.130Z,1637280558.130 [NAL9602](INFO): Powering down 2021-11-19T00:09:18.132Z,1637280558.132 [DAT](INFO): entering command mode 2021-11-19T00:09:18.535Z,1637280558.535 [DAT](INFO): DAT read: 2021-11-19T00:09:18.535Z,1637280558.535 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:18.939Z,1637280558.939 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:18.973Z,1637280558.973 [CBIT](INFO): Clearing failed state for component NAL9602 2021-11-19T00:09:18.973Z,1637280558.973 [NAL9602] No Fault, FailCount= 1 2021-11-19T00:09:19.339Z,1637280559.339 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:19.744Z,1637280559.744 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:20.147Z,1637280560.147 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:20.555Z,1637280560.555 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:20.959Z,1637280560.959 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:21.363Z,1637280561.363 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:21.763Z,1637280561.763 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:22.165Z,1637280562.165 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:22.575Z,1637280562.575 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:22.975Z,1637280562.975 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:23.386Z,1637280563.386 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:23.787Z,1637280563.787 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:24.187Z,1637280564.187 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:24.595Z,1637280564.595 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:25.002Z,1637280565.002 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:25.402Z,1637280565.402 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:25.801Z,1637280565.801 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:26.207Z,1637280566.207 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:26.617Z,1637280566.617 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:27.019Z,1637280567.019 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:27.423Z,1637280567.423 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:27.827Z,1637280567.827 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:28.231Z,1637280568.231 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:28.635Z,1637280568.635 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:29.039Z,1637280569.039 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:29.439Z,1637280569.439 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:29.850Z,1637280569.850 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:30.247Z,1637280570.247 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:30.648Z,1637280570.648 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:31.059Z,1637280571.059 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:31.465Z,1637280571.465 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:31.860Z,1637280571.860 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:32.267Z,1637280572.267 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:32.675Z,1637280572.675 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:33.079Z,1637280573.079 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:33.483Z,1637280573.483 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:33.483Z,1637280573.483 [DAT](FAULT): failed to enter command mode 2021-11-19T00:09:33.888Z,1637280573.888 [DAT](INFO): entering command mode 2021-11-19T00:09:34.287Z,1637280574.287 [DAT](INFO): DAT read: user:1> 2021-11-19T00:09:34.288Z,1637280574.288 [DAT](INFO): DAT read: Command '+++' not found 2021-11-19T00:09:34.288Z,1637280574.288 [DAT](INFO): DAT read: Error 2021-11-19T00:09:34.289Z,1637280574.289 [DAT](INFO): setting verbose to 3 2021-11-19T00:09:34.699Z,1637280574.699 [DAT](INFO): DAT read: user:2> 2021-11-19T00:09:34.700Z,1637280574.700 [DAT](INFO): DAT read: Verbose | 3 2021-11-19T00:09:34.700Z,1637280574.700 [DAT](INFO): set verbose to 3 2021-11-19T00:09:34.700Z,1637280574.700 [DAT](INFO): setting DatVerbose to 27440 2021-11-19T00:09:35.095Z,1637280575.095 [DAT](INFO): DAT read: user:3> 2021-11-19T00:09:35.096Z,1637280575.096 [DAT](INFO): DAT read: DatVerbose | 27440 2021-11-19T00:09:35.096Z,1637280575.096 [DAT](INFO): set DatVerbose to 27440 2021-11-19T00:09:35.097Z,1637280575.097 [DAT](INFO): setting transmit power to 8 2021-11-19T00:09:35.499Z,1637280575.499 [DAT](INFO): DAT read: user:4> 2021-11-19T00:09:35.500Z,1637280575.500 [DAT](INFO): DAT read: TxPower | 8 (Max) 2021-11-19T00:09:35.500Z,1637280575.500 [DAT](INFO): set transmit power to 8 2021-11-19T00:09:35.501Z,1637280575.501 [DAT](INFO): setting local address to 7 2021-11-19T00:09:35.908Z,1637280575.908 [DAT](INFO): DAT read: user:5> 2021-11-19T00:09:35.909Z,1637280575.909 [DAT](INFO): DAT read: LocalAddr | 7 2021-11-19T00:09:35.909Z,1637280575.909 [DAT](INFO): set local address to 7 2021-11-19T00:09:36.022Z,1637280576.022 [DAT](CRITICAL): SIGSEGV: address not mapped to object 2021-11-19T00:09:36.022Z,1637280576.022 [DAT] Software Fault, FailCount= 1 2021-11-19T00:09:36.022Z,1637280576.022 [DAT](ERROR): Software Fault 2021-11-19T00:09:36.023Z,1637280576.023 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-19T00:09:36.023Z,1637280576.023 [SyncHandler](INFO): Protected caller Thread ID is 1223 2021-11-19T00:09:36.041Z,1637280576.041 [CBIT](INFO): Critical error at 20211119T000936 2021-11-19T00:09:36.044Z,1637280576.044 [CBIT](ERROR): Software Fault in component: DAT 2021-11-19T00:09:36.306Z,1637280576.306 [DAT](INFO): Powering down 2021-11-19T00:09:37.132Z,1637280577.132 [CBIT](INFO): Clearing failed state for component DAT 2021-11-19T00:09:37.132Z,1637280577.132 [DAT] No Fault, FailCount= 1 2021-11-19T00:09:39.542Z,1637280579.542 [DAT](INFO): Powering up 2021-11-19T00:09:39.543Z,1637280579.543 [DAT](DEBUG): Initializing DAT. 2021-11-19T00:09:48.430Z,1637280588.430 [NAL9602](INFO): Powering up NAL9602 2021-11-19T00:09:51.259Z,1637280591.259 [DAT](INFO): DAT read: 2021-11-19T00:09:51.260Z,1637280591.260 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2021-11-19T00:09:52.871Z,1637280592.871 [DAT](INFO): DAT read: MF Frequency Band 2021-11-19T00:09:52.873Z,1637280592.873 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2 2021-11-19T00:09:52.874Z,1637280592.874 [DAT](INFO): DAT read: Nov 19 2021 00:09:12 2021-11-19T00:09:54.087Z,1637280594.087 [DAT](INFO): DAT read: Features enabled [Bearing] 2021-11-19T00:09:54.089Z,1637280594.089 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2021-11-19T00:09:54.089Z,1637280594.089 [DAT](INFO): commRate: 1200 2021-11-19T00:09:55.350Z,1637280595.350 [CommandExec](IMPORTANT): got command restart system 2021-11-19T00:09:56.101Z,1637280596.101 [DAT](INFO): entering command mode 2021-11-19T00:09:56.507Z,1637280596.507 [DAT](INFO): DAT read: 2021-11-19T00:09:56.508Z,1637280596.508 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:56.911Z,1637280596.911 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:57.322Z,1637280597.322 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:57.413Z,1637280597.413 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-19T00:09:57.413Z,1637280597.413 [CommandExec](INFO): Uninitializing the command executive. 2021-11-19T00:09:57.413Z,1637280597.413 [CommandExec](INFO): Uninitializing the command scheduler. 2021-11-19T00:09:57.414Z,1637280597.414 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:57.577Z,1637280597.577 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-11-19T00:09:57.577Z,1637280597.577 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-11-19T00:09:57.577Z,1637280597.577 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:57.578Z,1637280597.578 [NavChartDb](INFO): Join timeout helper Thread ID is 1229 2021-11-19T00:09:57.723Z,1637280597.723 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:57.905Z,1637280597.905 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-19T00:09:57.905Z,1637280597.905 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:57.921Z,1637280597.921 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-11-19T00:09:57.921Z,1637280597.921 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:57.921Z,1637280597.921 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1230 2021-11-19T00:09:58.123Z,1637280598.123 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:58.141Z,1637280598.141 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-19T00:09:58.141Z,1637280598.141 [WetLabsBB2FL](INFO): Powering down 2021-11-19T00:09:58.142Z,1637280598.142 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:58.161Z,1637280598.161 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-11-19T00:09:58.161Z,1637280598.161 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:58.161Z,1637280598.161 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1231 2021-11-19T00:09:58.527Z,1637280598.527 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:58.849Z,1637280598.849 [CTD_Seabird](INFO): Powering down 2021-11-19T00:09:58.861Z,1637280598.861 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-19T00:09:58.861Z,1637280598.861 [CTD_Seabird](INFO): Powering down 2021-11-19T00:09:58.878Z,1637280598.878 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:58.889Z,1637280598.889 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-11-19T00:09:58.889Z,1637280598.889 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:58.890Z,1637280598.890 [Radio_Surface](INFO): Join timeout helper Thread ID is 1232 2021-11-19T00:09:58.950Z,1637280598.950 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:59.105Z,1637280599.105 [Radio_Surface](INFO): Powering down 2021-11-19T00:09:59.106Z,1637280599.106 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-19T00:09:59.107Z,1637280599.107 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:59.117Z,1637280599.117 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-11-19T00:09:59.117Z,1637280599.117 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-11-19T00:09:59.117Z,1637280599.117 [Onboard](INFO): Join timeout helper Thread ID is 1233 2021-11-19T00:09:59.340Z,1637280599.340 [NAL9602](INFO): NAL9602 initialized 2021-11-19T00:09:59.341Z,1637280599.341 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:09:59.747Z,1637280599.747 [DAT](DEBUG): checking for command mode acknowledgment 2021-11-19T00:10:00.162Z,1637280600.162 [DAT](DEBUG): checking for command mode acknowledgment