2023-11-29T23:43:44.375Z,1701301424.375 [Supervisor](DEBUG): Initializing supervisor. 2023-11-29T23:43:44.379Z,1701301424.379 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-11-29T23:43:44.380Z,1701301424.380 [SyncHandler](INFO): Protected caller Thread ID is 834 2023-11-29T23:43:44.381Z,1701301424.381 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-11-29T23:43:44.382Z,1701301424.382 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-11-29T23:43:44.382Z,1701301424.382 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 835 2023-11-29T23:43:44.386Z,1701301424.386 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-11-29T23:43:44.403Z,1701301424.403 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-11-29T23:43:44.404Z,1701301424.404 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-11-29T23:43:44.405Z,1701301424.405 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 836 2023-11-29T23:43:44.409Z,1701301424.409 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-11-29T23:43:44.410Z,1701301424.410 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-11-29T23:43:44.410Z,1701301424.410 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837 2023-11-29T23:43:44.412Z,1701301424.412 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-11-29T23:43:44.413Z,1701301424.413 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-11-29T23:43:44.413Z,1701301424.413 [logger ThreadHandler](INFO): Protected caller Thread ID is 838 2023-11-29T23:43:44.417Z,1701301424.417 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-11-29T23:43:44.418Z,1701301424.418 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-11-29T23:43:44.421Z,1701301424.421 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-11-29T23:43:44.725Z,1701301424.725 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-11-29T23:43:44.726Z,1701301424.726 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-11-29T23:43:44.805Z,1701301424.805 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-11-29T23:43:44.909Z,1701301424.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-11-29T23:43:44.911Z,1701301424.911 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-11-29T23:43:45.035Z,1701301425.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-11-29T23:43:45.036Z,1701301425.036 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-11-29T23:43:45.360Z,1701301425.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-11-29T23:43:45.361Z,1701301425.361 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-11-29T23:43:45.456Z,1701301425.456 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-11-29T23:43:45.457Z,1701301425.457 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-11-29T23:43:45.828Z,1701301425.828 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-11-29T23:43:45.830Z,1701301425.830 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-11-29T23:43:46.030Z,1701301426.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-11-29T23:43:46.031Z,1701301426.031 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-11-29T23:43:46.109Z,1701301426.109 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-11-29T23:43:46.324Z,1701301426.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-11-29T23:43:46.324Z,1701301426.324 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-11-29T23:43:46.533Z,1701301426.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-11-29T23:43:46.534Z,1701301426.534 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-11-29T23:43:47.033Z,1701301427.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-11-29T23:43:47.034Z,1701301427.034 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-11-29T23:43:47.213Z,1701301427.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-11-29T23:43:47.213Z,1701301427.213 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-11-29T23:43:47.483Z,1701301427.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-11-29T23:43:47.485Z,1701301427.485 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-11-29T23:43:48.313Z,1701301428.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-11-29T23:43:48.313Z,1701301428.313 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-11-29T23:43:48.809Z,1701301428.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-11-29T23:43:48.811Z,1701301428.811 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2023-11-29T23:43:48.813Z,1701301428.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2023-11-29T23:43:48.908Z,1701301428.908 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2023-11-29T23:43:49.023Z,1701301429.023 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2023-11-29T23:43:49.106Z,1701301429.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2023-11-29T23:43:49.212Z,1701301429.212 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2023-11-29T23:43:49.310Z,1701301429.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2023-11-29T23:43:49.570Z,1701301429.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-11-29T23:43:49.570Z,1701301429.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2023-11-29T23:43:49.681Z,1701301429.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2023-11-29T23:43:49.773Z,1701301429.773 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 2023-11-29T23:43:49.875Z,1701301429.875 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2023-11-29T23:43:50.002Z,1701301430.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2023-11-29T23:43:50.087Z,1701301430.087 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2023-11-29T23:43:50.090Z,1701301430.090 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-11-29T23:43:50.219Z,1701301430.219 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-11-29T23:43:50.220Z,1701301430.220 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-11-29T23:43:50.287Z,1701301430.287 [VerticalControl](DEBUG): Construct VerticalControl. 2023-11-29T23:43:50.347Z,1701301430.347 [VerticalControl] Loaded 2023-11-29T23:43:50.348Z,1701301430.348 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-11-29T23:43:50.351Z,1701301430.351 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-11-29T23:43:50.392Z,1701301430.392 [HorizontalControl] Loaded 2023-11-29T23:43:50.393Z,1701301430.393 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-11-29T23:43:50.395Z,1701301430.395 [SpeedControl](DEBUG): Construct SpeedControl. 2023-11-29T23:43:50.398Z,1701301430.398 [SpeedControl] Loaded 2023-11-29T23:43:50.398Z,1701301430.398 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-11-29T23:43:50.401Z,1701301430.401 [LoopControl](DEBUG): Construct LoopControl. 2023-11-29T23:43:50.401Z,1701301430.401 [LoopControl] Loaded 2023-11-29T23:43:50.402Z,1701301430.402 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-11-29T23:43:50.402Z,1701301430.402 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-11-29T23:43:50.403Z,1701301430.403 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-11-29T23:43:50.484Z,1701301430.484 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-11-29T23:43:50.485Z,1701301430.485 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-11-29T23:43:51.246Z,1701301431.246 [AHRS_M2] Loaded 2023-11-29T23:43:51.246Z,1701301431.246 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-11-29T23:43:51.496Z,1701301431.496 [BackseatComponent] Loaded 2023-11-29T23:43:51.497Z,1701301431.497 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-11-29T23:43:51.498Z,1701301431.498 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408844E0 2023-11-29T23:43:51.498Z,1701301431.498 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2023-11-29T23:43:51.501Z,1701301431.501 [LcmUniversalReporter] Loaded 2023-11-29T23:43:51.501Z,1701301431.501 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-11-29T23:43:52.312Z,1701301432.312 [BPC1] Loaded 2023-11-29T23:43:52.313Z,1701301432.313 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-11-29T23:43:52.455Z,1701301432.455 [DataOverHttps] Loaded 2023-11-29T23:43:52.455Z,1701301432.455 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-11-29T23:43:52.456Z,1701301432.456 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0 2023-11-29T23:43:52.456Z,1701301432.456 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921 2023-11-29T23:43:52.477Z,1701301432.477 [Depth_Keller] Loaded 2023-11-29T23:43:52.477Z,1701301432.477 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-11-29T23:43:52.482Z,1701301432.482 [DropWeight] Loaded 2023-11-29T23:43:52.482Z,1701301432.482 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-11-29T23:43:52.544Z,1701301432.544 [NAL9602] Loaded 2023-11-29T23:43:52.544Z,1701301432.544 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-11-29T23:43:52.574Z,1701301432.574 [Onboard] Loaded 2023-11-29T23:43:52.574Z,1701301432.574 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-11-29T23:43:52.575Z,1701301432.575 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0 2023-11-29T23:43:52.575Z,1701301432.575 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922 2023-11-29T23:43:52.587Z,1701301432.587 [Power24vConverter] Loaded 2023-11-29T23:43:52.587Z,1701301432.587 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-11-29T23:43:52.601Z,1701301432.601 [Radio_Surface] Loaded 2023-11-29T23:43:52.601Z,1701301432.601 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-11-29T23:43:52.602Z,1701301432.602 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409144E0 2023-11-29T23:43:52.602Z,1701301432.602 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923 2023-11-29T23:43:52.615Z,1701301432.615 [Sonardyne_Nano] Loaded 2023-11-29T23:43:52.616Z,1701301432.616 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-11-29T23:43:52.707Z,1701301432.707 [DAT] Loaded 2023-11-29T23:43:52.707Z,1701301432.707 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-11-29T23:43:52.708Z,1701301432.708 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409444E0 2023-11-29T23:43:52.708Z,1701301432.708 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924 2023-11-29T23:43:52.731Z,1701301432.731 [Waterlinked] Loaded 2023-11-29T23:43:52.731Z,1701301432.731 [ComponentRegistry](DEBUG): SyncComponent "Waterlinked" handled in the control thread. 2023-11-29T23:43:52.731Z,1701301432.731 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-11-29T23:43:52.732Z,1701301432.732 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-11-29T23:43:52.747Z,1701301432.747 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-11-29T23:43:52.748Z,1701301432.748 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-11-29T23:43:52.789Z,1701301432.789 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-11-29T23:43:52.791Z,1701301432.791 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-11-29T23:43:52.958Z,1701301432.958 [SBIT](DEBUG): Construct Startup Built In Test. 2023-11-29T23:43:52.967Z,1701301432.967 [SBIT] Loaded 2023-11-29T23:43:52.967Z,1701301432.967 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-11-29T23:43:52.970Z,1701301432.970 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-11-29T23:43:52.983Z,1701301432.983 [IBIT] Loaded 2023-11-29T23:43:52.983Z,1701301432.983 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-11-29T23:43:52.989Z,1701301432.989 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-11-29T23:43:53.093Z,1701301433.093 [CBIT] Loaded 2023-11-29T23:43:53.093Z,1701301433.093 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-11-29T23:43:53.093Z,1701301433.093 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-11-29T23:43:53.094Z,1701301433.094 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-11-29T23:43:53.448Z,1701301433.448 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-11-29T23:43:53.449Z,1701301433.449 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-11-29T23:43:53.530Z,1701301433.530 [DepthRateCalculator] Loaded 2023-11-29T23:43:53.531Z,1701301433.531 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-11-29T23:43:53.536Z,1701301433.536 [PitchRateCalculator] Loaded 2023-11-29T23:43:53.536Z,1701301433.536 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-11-29T23:43:53.546Z,1701301433.546 [SpeedCalculator] Loaded 2023-11-29T23:43:53.546Z,1701301433.546 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-11-29T23:43:53.551Z,1701301433.551 [YawRateCalculator] Loaded 2023-11-29T23:43:53.551Z,1701301433.551 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-11-29T23:43:53.570Z,1701301433.570 [ElevatorOffsetCalculator] Loaded 2023-11-29T23:43:53.570Z,1701301433.570 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-11-29T23:43:53.571Z,1701301433.571 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-11-29T23:43:53.571Z,1701301433.571 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-11-29T23:43:53.750Z,1701301433.750 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-11-29T23:43:53.751Z,1701301433.751 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-11-29T23:43:53.886Z,1701301433.886 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-11-29T23:43:53.886Z,1701301433.886 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-11-29T23:43:53.928Z,1701301433.928 [DeadReckonUsingSpeedCalculator] Loaded 2023-11-29T23:43:53.929Z,1701301433.929 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2023-11-29T23:43:53.971Z,1701301433.971 [DeadReckonWithRespectToSeafloor] Loaded 2023-11-29T23:43:53.971Z,1701301433.971 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2023-11-29T23:43:53.985Z,1701301433.985 [NavChart] Loaded 2023-11-29T23:43:53.986Z,1701301433.986 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-11-29T23:43:53.991Z,1701301433.991 [UniversalFixResidualReporter] Loaded 2023-11-29T23:43:53.991Z,1701301433.991 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-11-29T23:43:53.992Z,1701301433.992 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-11-29T23:43:53.993Z,1701301433.993 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-11-29T23:43:54.151Z,1701301434.151 [BuoyancyServo] Loaded 2023-11-29T23:43:54.151Z,1701301434.151 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-11-29T23:43:54.171Z,1701301434.171 [ElevatorServo] Loaded 2023-11-29T23:43:54.172Z,1701301434.172 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-11-29T23:43:54.192Z,1701301434.192 [MassServo] Loaded 2023-11-29T23:43:54.192Z,1701301434.192 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-11-29T23:43:54.211Z,1701301434.211 [RudderServo] Loaded 2023-11-29T23:43:54.211Z,1701301434.211 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-11-29T23:43:54.225Z,1701301434.225 [ThrusterHE] Loaded 2023-11-29T23:43:54.226Z,1701301434.226 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-11-29T23:43:54.226Z,1701301434.226 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-11-29T23:43:54.227Z,1701301434.227 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-11-29T23:43:54.445Z,1701301434.445 [CTD_Seabird] Loaded 2023-11-29T23:43:54.445Z,1701301434.445 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-11-29T23:43:54.446Z,1701301434.446 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9E4E0 2023-11-29T23:43:54.447Z,1701301434.447 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 925 2023-11-29T23:43:54.480Z,1701301434.480 [ESPComponent] Loaded 2023-11-29T23:43:54.481Z,1701301434.481 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-11-29T23:43:54.501Z,1701301434.501 [PAR_Licor] Loaded 2023-11-29T23:43:54.501Z,1701301434.501 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-11-29T23:43:54.542Z,1701301434.542 [WetLabsBB2FL] Loaded 2023-11-29T23:43:54.542Z,1701301434.542 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-11-29T23:43:54.543Z,1701301434.543 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCE4E0 2023-11-29T23:43:54.543Z,1701301434.543 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 926 2023-11-29T23:43:54.544Z,1701301434.544 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-11-29T23:43:54.550Z,1701301434.550 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-11-29T23:43:54.553Z,1701301434.553 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-11-29T23:43:54.565Z,1701301434.565 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-11-29T23:43:54.566Z,1701301434.566 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFE4E0 2023-11-29T23:43:54.566Z,1701301434.566 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2023-11-29T23:43:54.570Z,1701301434.570 [Supervisor](INFO): Main Thread ID is 828 2023-11-29T23:43:54.570Z,1701301434.570 [Supervisor](DEBUG): Running supervisor. 2023-11-29T23:43:54.571Z,1701301434.571 [CommandExec ThreadHandler](INFO): Handler Thread ID is 928 2023-11-29T23:43:54.572Z,1701301434.572 [CommandExec](INFO): Initializing the command executive. 2023-11-29T23:43:54.573Z,1701301434.573 [CommandLine ThreadHandler](INFO): Handler Thread ID is 929 2023-11-29T23:43:54.575Z,1701301434.575 [controlThread ThreadHandler](INFO): Handler Thread ID is 930 2023-11-29T23:43:54.576Z,1701301434.576 [controlThread](DEBUG): Initializing ControlThread 2023-11-29T23:43:54.576Z,1701301434.576 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-11-29T23:43:54.578Z,1701301434.578 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-11-29T23:43:54.579Z,1701301434.579 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-11-29T23:43:54.579Z,1701301434.579 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-11-29T23:43:54.586Z,1701301434.586 [SBIT](INFO): Initialize SBIT Component. 2023-11-29T23:43:54.586Z,1701301434.586 [SBIT](IMPORTANT): git: 2023-10-24_A 2023-11-29T23:43:54.587Z,1701301434.587 [SBIT](INFO): git hash: dfa22713be92246460bd2ec0e2f51182051bcc4a 2023-11-29T23:43:54.587Z,1701301434.587 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-11-29T23:43:54.588Z,1701301434.588 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2023-11-29T23:43:54.589Z,1701301434.589 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 2023-11-29T23:43:54.590Z,1701301434.590 [IBIT](INFO): Initialize IBIT Component. 2023-11-29T23:43:54.591Z,1701301434.591 [CBIT](DEBUG): Initialize CBIT Component. 2023-11-29T23:43:54.591Z,1701301434.591 [logger ThreadHandler](INFO): Handler Thread ID is 931 2023-11-29T23:43:54.604Z,1701301434.604 [CBIT](DEBUG): Initialized mux pins. 2023-11-29T23:43:54.604Z,1701301434.604 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2023-11-29T23:43:54.604Z,1701301434.604 [CBIT](DEBUG): Initializing the watchdog timer. 2023-11-29T23:43:54.612Z,1701301434.612 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 932 2023-11-29T23:43:54.624Z,1701301434.624 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933 2023-11-29T23:43:54.626Z,1701301434.626 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-11-29T23:43:54.628Z,1701301434.628 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-11-29T23:43:54.628Z,1701301434.628 [CBIT](DEBUG): Initializing heartbeat. 2023-11-29T23:43:54.636Z,1701301434.636 [Onboard ThreadHandler](INFO): Handler Thread ID is 934 2023-11-29T23:43:54.666Z,1701301434.666 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935 2023-11-29T23:43:54.673Z,1701301434.673 [DAT ThreadHandler](INFO): Handler Thread ID is 936 2023-11-29T23:43:54.673Z,1701301434.673 [DAT](INFO): Powering up 2023-11-29T23:43:54.674Z,1701301434.674 [DAT](DEBUG): Initializing DAT. 2023-11-29T23:43:54.678Z,1701301434.678 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938 2023-11-29T23:43:54.678Z,1701301434.678 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-11-29T23:43:54.685Z,1701301434.685 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940 2023-11-29T23:43:54.689Z,1701301434.689 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942 2023-11-29T23:43:54.690Z,1701301434.690 [WetLabsBB2FL](INFO): Powering up 2023-11-29T23:43:54.698Z,1701301434.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2023-11-29T23:43:54.698Z,1701301434.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2023-11-29T23:43:54.698Z,1701301434.698 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2023-11-29T23:43:54.698Z,1701301434.698 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2023-11-29T23:43:54.700Z,1701301434.700 [CBIT](DEBUG): Deactivating GF circuits. 2023-11-29T23:43:54.700Z,1701301434.700 [CBIT](DEBUG): Deactivating emergency mode. 2023-11-29T23:43:54.699Z,1701301434.699 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2023-11-29T23:43:54.700Z,1701301434.700 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2023-11-29T23:43:54.740Z,1701301434.740 [CBIT](DEBUG): Backplane powered. 2023-11-29T23:43:54.740Z,1701301434.740 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-11-29T23:43:54.740Z,1701301434.740 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-11-29T23:43:54.741Z,1701301434.741 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-11-29T23:43:54.741Z,1701301434.741 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-11-29T23:43:54.741Z,1701301434.741 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-11-29T23:43:54.742Z,1701301434.742 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2023-11-29T23:43:54.743Z,1701301434.743 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-11-29T23:43:54.744Z,1701301434.744 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-11-29T23:43:54.749Z,1701301434.749 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-11-29T23:43:54.779Z,1701301434.779 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-11-29T23:43:54.803Z,1701301434.803 [MissionManager](DEBUG): 2023-11-29T23:43:54.816Z,1701301434.816 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-11-29T23:43:54.862Z,1701301434.862 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-11-29T23:43:54.863Z,1701301434.863 [Default:A.Wait](DEBUG): Construct Wait. 2023-11-29T23:43:54.865Z,1701301434.865 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-11-29T23:43:54.893Z,1701301434.893 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-11-29T23:43:54.895Z,1701301434.895 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-11-29T23:43:54.923Z,1701301434.923 [Default:E.Execute](DEBUG): Construct Execute. 2023-11-29T23:43:54.933Z,1701301434.933 [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. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-11-29T23:43:54.948Z,1701301434.948 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,Waterlinked,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-11-29T23:43:54.955Z,1701301434.955 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-11-29T23:43:55.068Z,1701301435.068 [Radio_Surface](INFO): Powering up 2023-11-29T23:43:55.085Z,1701301435.085 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m 2023-11-29T23:43:55.087Z,1701301435.087 [Power24vConverter](INFO): Powering up. 2023-11-29T23:43:55.087Z,1701301435.087 [Sonardyne_Nano](INFO): Initializing. 2023-11-29T23:43:55.114Z,1701301435.114 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-11-29T23:43:55.140Z,1701301435.140 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-11-29T23:43:55.148Z,1701301435.148 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-11-29T23:43:55.149Z,1701301435.149 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-11-29T23:43:55.156Z,1701301435.156 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-11-29T23:43:55.157Z,1701301435.157 [MassServo](DEBUG): Initializing EZServoServo. 2023-11-29T23:43:55.164Z,1701301435.164 [MassServo](DEBUG): Initializing MassServo. 2023-11-29T23:43:55.165Z,1701301435.165 [RudderServo](DEBUG): Initializing EZServoServo. 2023-11-29T23:43:55.172Z,1701301435.172 [RudderServo](DEBUG): Initializing RudderServo. 2023-11-29T23:43:55.173Z,1701301435.173 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-11-29T23:43:55.180Z,1701301435.180 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-11-29T23:43:55.406Z,1701301435.406 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2023-11-29T23:43:55.406Z,1701301435.406 [DropWeight] Hardware Fault, FailCount= 1 2023-11-29T23:43:55.406Z,1701301435.406 [DropWeight](ERROR): Hardware Fault 2023-11-29T23:43:55.438Z,1701301435.438 [CommandExec](FAULT): Scheduling is paused 2023-11-29T23:43:55.438Z,1701301435.438 [CBIT](INFO): Critical error at 20231129T234355 2023-11-29T23:43:55.438Z,1701301435.438 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-11-29T23:43:55.445Z,1701301435.445 [CBIT](ERROR): Hardware Fault in component: DropWeight 2023-11-29T23:43:55.446Z,1701301435.446 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2023-11-29T23:43:55.765Z,1701301435.765 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.426822 decibar, -1.416301 m 2023-11-29T23:43:55.786Z,1701301435.786 [CBIT](INFO): Critical error at 20231129T234355 2023-11-29T23:43:58.316Z,1701301438.316 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:43:58.705Z,1701301438.705 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:43:59.124Z,1701301439.124 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:43:59.528Z,1701301439.528 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:43:59.928Z,1701301439.928 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:43:59.984Z,1701301439.984 [WetLabsBB2FL] Communications Fault, FailCount= 1 2023-11-29T23:43:59.984Z,1701301439.984 [WetLabsBB2FL](ERROR): Communications Fault 2023-11-29T23:43:59.994Z,1701301439.994 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:00.324Z,1701301440.324 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:00.484Z,1701301440.484 [WetLabsBB2FL](INFO): Powering down 2023-11-29T23:44:00.716Z,1701301440.716 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:00.905Z,1701301440.905 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-11-29T23:44:01.120Z,1701301441.120 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:01.136Z,1701301441.136 [Radio_Surface](INFO): Powering down 2023-11-29T23:44:01.304Z,1701301441.304 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-11-29T23:44:01.304Z,1701301441.304 [WetLabsBB2FL] No Fault, FailCount= 1 2023-11-29T23:44:01.536Z,1701301441.536 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:01.705Z,1701301441.705 [WetLabsBB2FL](INFO): Powering up 2023-11-29T23:44:01.924Z,1701301441.924 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:02.340Z,1701301442.340 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:02.736Z,1701301442.736 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:03.136Z,1701301443.136 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:03.540Z,1701301443.540 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:03.956Z,1701301443.956 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:04.352Z,1701301444.352 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:04.784Z,1701301444.784 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:04.849Z,1701301444.849 [CBIT](CRITICAL): Environmental Failure. Press:14.707089 PSI. Humidity:35%. Temp:28 C. ABORTING MISSION 2023-11-29T23:44:05.004Z,1701301445.004 [DAT](INFO): DAT read: 2023-11-29T23:44:05.006Z,1701301445.006 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2023-11-29T23:44:05.160Z,1701301445.160 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:05.218Z,1701301445.218 [CBIT](INFO): Critical error at 20231129T234404 2023-11-29T23:44:05.564Z,1701301445.564 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:05.976Z,1701301445.976 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:06.265Z,1701301446.265 [DAT](INFO): DAT read: MF Frequency Band 2023-11-29T23:44:06.266Z,1701301446.266 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.10.9 2023-11-29T23:44:06.267Z,1701301446.267 [DAT](INFO): DAT read: Nov 29 2023 23:29:04 2023-11-29T23:44:06.376Z,1701301446.376 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:06.800Z,1701301446.800 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:06.988Z,1701301446.988 [WetLabsBB2FL] Communications Fault, FailCount= 2 2023-11-29T23:44:06.988Z,1701301446.988 [WetLabsBB2FL](ERROR): Communications Fault 2023-11-29T23:44:07.021Z,1701301447.021 [DAT](INFO): DAT read: Features enabled [Bearing] 2023-11-29T23:44:07.022Z,1701301447.022 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2023-11-29T23:44:07.023Z,1701301447.023 [DAT](INFO): commRate: 800 2023-11-29T23:44:07.188Z,1701301447.188 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:07.242Z,1701301447.242 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:07.488Z,1701301447.488 [WetLabsBB2FL](INFO): Powering down 2023-11-29T23:44:07.588Z,1701301447.588 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:07.728Z,1701301447.728 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:07.728Z,1701301447.728 [CTD_Seabird] Communications Fault, FailCount= 1 2023-11-29T23:44:07.728Z,1701301447.728 [CTD_Seabird](ERROR): Communications Fault 2023-11-29T23:44:07.996Z,1701301447.996 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:07.996Z,1701301447.996 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:07.996Z,1701301447.996 [AHRS_M2] Communications Fault, FailCount= 1 2023-11-29T23:44:07.996Z,1701301447.996 [AHRS_M2](ERROR): Communications Fault 2023-11-29T23:44:08.064Z,1701301448.064 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-11-29T23:44:08.065Z,1701301448.065 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-11-29T23:44:08.133Z,1701301448.133 [CTD_Seabird](INFO): Powering down 2023-11-29T23:44:08.285Z,1701301448.285 [AHRS_M2](INFO): Powering down 2023-11-29T23:44:08.413Z,1701301448.413 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-11-29T23:44:08.413Z,1701301448.413 [WetLabsBB2FL] No Fault, FailCount= 2 2023-11-29T23:44:08.704Z,1701301448.704 [WetLabsBB2FL](INFO): Powering up 2023-11-29T23:44:09.088Z,1701301449.088 [DAT](INFO): entering command mode 2023-11-29T23:44:09.165Z,1701301449.165 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-11-29T23:44:09.165Z,1701301449.165 [AHRS_M2] No Fault, FailCount= 1 2023-11-29T23:44:09.165Z,1701301449.165 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-11-29T23:44:09.165Z,1701301449.165 [CTD_Seabird] No Fault, FailCount= 1 2023-11-29T23:44:09.288Z,1701301449.288 [DAT](INFO): DAT read: 2023-11-29T23:44:09.289Z,1701301449.289 [DAT](INFO): DAT read: user:1> 2023-11-29T23:44:09.289Z,1701301449.289 [DAT](INFO): setting verbose to 3 2023-11-29T23:44:09.476Z,1701301449.476 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-11-29T23:44:09.497Z,1701301449.497 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-11-29T23:44:09.541Z,1701301449.541 [DAT](INFO): DAT read: user:1> 2023-11-29T23:44:09.542Z,1701301449.542 [DAT](INFO): DAT read: Verbose | 3 2023-11-29T23:44:09.542Z,1701301449.542 [DAT](INFO): set verbose to 3 2023-11-29T23:44:09.542Z,1701301449.542 [DAT](INFO): setting DatVerbose to 27440 2023-11-29T23:44:09.792Z,1701301449.792 [DAT](INFO): DAT read: user:2> 2023-11-29T23:44:09.793Z,1701301449.793 [DAT](INFO): DAT read: DatVerbose | 27440 2023-11-29T23:44:09.794Z,1701301449.794 [DAT](INFO): set DatVerbose to 27440 2023-11-29T23:44:09.794Z,1701301449.794 [DAT](INFO): setting transmit power to 8 2023-11-29T23:44:10.044Z,1701301450.044 [DAT](INFO): DAT read: user:3> 2023-11-29T23:44:10.045Z,1701301450.045 [DAT](INFO): DAT read: TxPower | 8 (Max) 2023-11-29T23:44:10.046Z,1701301450.046 [DAT](INFO): set transmit power to 8 2023-11-29T23:44:10.046Z,1701301450.046 [DAT](INFO): setting local address to 1 2023-11-29T23:44:10.296Z,1701301450.296 [DAT](INFO): DAT read: user:4> 2023-11-29T23:44:10.297Z,1701301450.297 [DAT](INFO): DAT read: LocalAddr | 1 2023-11-29T23:44:10.298Z,1701301450.298 [DAT](INFO): set local address to 1 2023-11-29T23:44:10.299Z,1701301450.299 [DAT](INFO): Setting time to: 23:44:10 And date to:11/29/2023 2023-11-29T23:44:10.548Z,1701301450.548 [DAT](INFO): DAT read: user:5> 2023-11-29T23:44:10.549Z,1701301450.549 [DAT](INFO): DAT read: Wed Nov 29, 2023 23:44:10 2023-11-29T23:44:10.550Z,1701301450.550 [DAT](INFO): Local DAT time set to Wed Nov 29, 2023 23:44:10 2023-11-29T23:44:10.550Z,1701301450.550 [DAT](INFO): DAT read: Ok 2023-11-29T23:44:10.550Z,1701301450.550 [DAT](INFO): unknown deviceResponse_: Ok 2023-11-29T23:44:12.844Z,1701301452.844 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:13.252Z,1701301453.252 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:13.648Z,1701301453.648 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:14.004Z,1701301454.004 [WetLabsBB2FL] Communications Fault, FailCount= 3 2023-11-29T23:44:14.004Z,1701301454.004 [WetLabsBB2FL](ERROR): Communications Fault 2023-11-29T23:44:14.060Z,1701301454.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:14.123Z,1701301454.123 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:14.436Z,1701301454.436 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:14.501Z,1701301454.501 [WetLabsBB2FL](INFO): Powering down 2023-11-29T23:44:14.860Z,1701301454.860 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:15.480Z,1701301455.480 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:15.540Z,1701301455.540 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-11-29T23:44:15.540Z,1701301455.540 [WetLabsBB2FL] No Fault, FailCount= 3 2023-11-29T23:44:15.713Z,1701301455.713 [WetLabsBB2FL](INFO): Powering up 2023-11-29T23:44:15.880Z,1701301455.880 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:16.288Z,1701301456.288 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:16.476Z,1701301456.476 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:17.092Z,1701301457.092 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:17.504Z,1701301457.504 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:17.908Z,1701301457.908 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:18.300Z,1701301458.300 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:18.716Z,1701301458.716 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:19.120Z,1701301459.120 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:19.520Z,1701301459.520 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:19.928Z,1701301459.928 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:20.324Z,1701301460.324 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:20.736Z,1701301460.736 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:21.008Z,1701301461.008 [WetLabsBB2FL] Communications Fault, FailCount= 4 2023-11-29T23:44:21.008Z,1701301461.008 [WetLabsBB2FL](ERROR): Communications Fault 2023-11-29T23:44:21.144Z,1701301461.144 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:21.201Z,1701301461.201 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:21.508Z,1701301461.508 [WetLabsBB2FL](INFO): Powering down 2023-11-29T23:44:21.536Z,1701301461.536 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:21.936Z,1701301461.936 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:22.344Z,1701301462.344 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:22.344Z,1701301462.344 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:22.344Z,1701301462.344 [AHRS_M2] Communications Fault, FailCount= 2 2023-11-29T23:44:22.344Z,1701301462.344 [AHRS_M2](ERROR): Communications Fault 2023-11-29T23:44:22.402Z,1701301462.402 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-11-29T23:44:22.402Z,1701301462.402 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 2023-11-29T23:44:22.402Z,1701301462.402 [WetLabsBB2FL] No Fault, FailCount= 4 2023-11-29T23:44:22.430Z,1701301462.430 [AHRS_M2](INFO): Powering down 2023-11-29T23:44:22.529Z,1701301462.529 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:22.530Z,1701301462.530 [CTD_Seabird] Communications Fault, FailCount= 2 2023-11-29T23:44:22.530Z,1701301462.530 [CTD_Seabird](ERROR): Communications Fault 2023-11-29T23:44:22.556Z,1701301462.556 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-11-29T23:44:22.721Z,1701301462.721 [WetLabsBB2FL](INFO): Powering up 2023-11-29T23:44:22.838Z,1701301462.838 [NAL9602](INFO): Powering up NAL9602 2023-11-29T23:44:22.932Z,1701301462.932 [CTD_Seabird](INFO): Powering down 2023-11-29T23:44:23.248Z,1701301463.248 [Waterlinked](FAULT): Could not initialize. 2023-11-29T23:44:23.249Z,1701301463.249 [Waterlinked] Communications Fault, FailCount= 1 2023-11-29T23:44:23.249Z,1701301463.249 [Waterlinked](ERROR): Communications Fault 2023-11-29T23:44:23.300Z,1701301463.300 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-11-29T23:44:23.300Z,1701301463.300 [AHRS_M2] No Fault, FailCount= 2 2023-11-29T23:44:23.300Z,1701301463.300 [CBIT](ERROR): Communications Fault in component: Waterlinked 2023-11-29T23:44:23.641Z,1701301463.641 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-11-29T23:44:23.743Z,1701301463.743 [Waterlinked](INFO): Powering down 2023-11-29T23:44:24.100Z,1701301464.100 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2023-11-29T23:44:24.100Z,1701301464.100 [CTD_Seabird] No Fault, FailCount= 2 2023-11-29T23:44:24.276Z,1701301464.276 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-11-29T23:44:24.500Z,1701301464.500 [CBIT](INFO): Clearing failed state for component Waterlinked 2023-11-29T23:44:24.500Z,1701301464.500 [Waterlinked] No Fault, FailCount= 1 2023-11-29T23:44:27.200Z,1701301467.200 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:27.604Z,1701301467.604 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:27.820Z,1701301467.820 [Radio_Surface](INFO): Powering up 2023-11-29T23:44:27.000Z,1701301468.000 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:28.032Z,1701301468.032 [WetLabsBB2FL] Communications Fault, FailCount= 5 2023-11-29T23:44:28.032Z,1701301468.032 [WetLabsBB2FL](ERROR): Communications Fault 2023-11-29T23:44:28.077Z,1701301468.077 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:28.077Z,1701301468.077 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 2023-11-29T23:44:28.408Z,1701301468.408 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:28.532Z,1701301468.532 [WetLabsBB2FL](INFO): Powering down 2023-11-29T23:44:28.808Z,1701301468.808 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:29.212Z,1701301469.212 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:29.225Z,1701301469.225 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.771583 decibar, -0.765892 m 2023-11-29T23:44:29.616Z,1701301469.616 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:30.068Z,1701301470.068 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:30.472Z,1701301470.472 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:30.868Z,1701301470.868 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:31.280Z,1701301471.280 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:31.288Z,1701301471.288 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2023-11-29T23:44:31.288Z,1701301471.288 [Depth_Keller] Data Fault, FailCount= 1 2023-11-29T23:44:31.289Z,1701301471.289 [Depth_Keller](ERROR): Data Fault 2023-11-29T23:44:31.354Z,1701301471.354 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-11-29T23:44:31.355Z,1701301471.355 [CBIT](INFO): Clearing failed state for component Depth_Keller 2023-11-29T23:44:31.355Z,1701301471.355 [Depth_Keller] No Fault, FailCount= 1 2023-11-29T23:44:31.680Z,1701301471.680 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:32.092Z,1701301472.092 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:32.101Z,1701301472.101 [Depth_Keller](ERROR): Pressure or depth reading out of range: -2.822604 decibar, -2.801801 m 2023-11-29T23:44:32.496Z,1701301472.496 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:32.900Z,1701301472.900 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:33.292Z,1701301473.292 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:33.712Z,1701301473.712 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:33.721Z,1701301473.721 [NAL9602](INFO): NAL9602 initialized 2023-11-29T23:44:33.880Z,1701301473.880 [Radio_Surface](INFO): Powering down 2023-11-29T23:44:34.108Z,1701301474.108 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:34.116Z,1701301474.116 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2023-11-29T23:44:34.116Z,1701301474.116 [Depth_Keller] Data Fault, FailCount= 2 2023-11-29T23:44:34.117Z,1701301474.117 [Depth_Keller](ERROR): Data Fault 2023-11-29T23:44:34.180Z,1701301474.180 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-11-29T23:44:34.180Z,1701301474.180 [CBIT](INFO): Clearing failed state for component Depth_Keller 2023-11-29T23:44:34.180Z,1701301474.180 [Depth_Keller] No Fault, FailCount= 2 2023-11-29T23:44:34.504Z,1701301474.504 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:34.908Z,1701301474.908 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:34.917Z,1701301474.917 [Depth_Keller](ERROR): Pressure or depth reading out of range: -5.070198 decibar, -5.032856 m 2023-11-29T23:44:35.328Z,1701301475.328 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:35.744Z,1701301475.744 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:36.144Z,1701301476.144 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:36.540Z,1701301476.540 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:36.540Z,1701301476.540 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:36.540Z,1701301476.540 [AHRS_M2] Communications Fault, FailCount= 3 2023-11-29T23:44:36.540Z,1701301476.540 [AHRS_M2](ERROR): Communications Fault 2023-11-29T23:44:36.612Z,1701301476.612 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-11-29T23:44:36.625Z,1701301476.625 [AHRS_M2](INFO): Powering down 2023-11-29T23:44:36.705Z,1701301476.705 [Depth_Keller](FAULT): Pressure or depth reading out of range for max 5 samples 2023-11-29T23:44:36.705Z,1701301476.705 [Depth_Keller] Data Fault, FailCount= 3 2023-11-29T23:44:36.705Z,1701301476.705 [Depth_Keller](ERROR): Data Fault 2023-11-29T23:44:36.769Z,1701301476.769 [CBIT](ERROR): Data Fault in component: Depth_Keller 2023-11-29T23:44:36.770Z,1701301476.770 [CBIT](CRITICAL): Data Fault in component: Depth_Keller 2023-11-29T23:44:37.094Z,1701301477.094 [CBIT](INFO): Critical error at 20231129T234436 2023-11-29T23:44:37.328Z,1701301477.328 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2023-11-29T23:44:37.328Z,1701301477.328 [CTD_Seabird] Communications Fault, FailCount= 3 2023-11-29T23:44:37.328Z,1701301477.328 [CTD_Seabird](ERROR): Communications Fault 2023-11-29T23:44:37.508Z,1701301477.508 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-11-29T23:44:37.508Z,1701301477.508 [AHRS_M2] No Fault, FailCount= 3 2023-11-29T23:44:37.509Z,1701301477.509 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2023-11-29T23:44:37.509Z,1701301477.509 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2023-11-29T23:44:37.740Z,1701301477.740 [CTD_Seabird](INFO): Powering down 2023-11-29T23:44:37.837Z,1701301477.837 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-11-29T23:44:41.396Z,1701301481.396 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:41.788Z,1701301481.788 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:42.200Z,1701301482.200 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:42.612Z,1701301482.612 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:43.028Z,1701301483.028 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:43.440Z,1701301483.440 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:43.832Z,1701301483.832 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:44.232Z,1701301484.232 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:44.644Z,1701301484.644 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:45.048Z,1701301485.048 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:45.444Z,1701301485.444 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:45.848Z,1701301485.848 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:46.260Z,1701301486.260 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:46.672Z,1701301486.672 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:47.080Z,1701301487.080 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:47.584Z,1701301487.584 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:47.980Z,1701301487.980 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:48.388Z,1701301488.388 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:48.800Z,1701301488.800 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:49.208Z,1701301489.208 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:49.604Z,1701301489.604 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:49.996Z,1701301489.996 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:50.400Z,1701301490.400 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-11-29T23:44:50.816Z,1701