2024-10-10T22:13:58.077Z,1728598438.077 [Supervisor](DEBUG): Initializing supervisor. 2024-10-10T22:13:58.081Z,1728598438.081 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-10-10T22:13:58.081Z,1728598438.081 [SyncHandler](INFO): Protected caller Thread ID is 837 2024-10-10T22:13:58.082Z,1728598438.082 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-10-10T22:13:58.083Z,1728598438.083 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-10-10T22:13:58.083Z,1728598438.083 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2024-10-10T22:13:58.087Z,1728598438.087 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-10-10T22:13:58.104Z,1728598438.104 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-10-10T22:13:58.105Z,1728598438.105 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-10-10T22:13:58.106Z,1728598438.106 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2024-10-10T22:13:58.110Z,1728598438.110 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-10-10T22:13:58.111Z,1728598438.111 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-10-10T22:13:58.111Z,1728598438.111 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2024-10-10T22:13:58.113Z,1728598438.113 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-10-10T22:13:58.114Z,1728598438.114 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-10-10T22:13:58.115Z,1728598438.115 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2024-10-10T22:13:58.118Z,1728598438.118 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-10-10T22:13:58.119Z,1728598438.119 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-10-10T22:13:58.123Z,1728598438.123 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-10-10T22:13:58.405Z,1728598438.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-10-10T22:13:58.407Z,1728598438.407 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-10-10T22:13:58.502Z,1728598438.502 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-10-10T22:13:59.064Z,1728598439.064 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-10-10T22:13:59.066Z,1728598439.066 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-10-10T22:13:59.434Z,1728598439.434 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-10-10T22:13:59.436Z,1728598439.436 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-10-10T22:13:59.544Z,1728598439.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-10-10T22:13:59.546Z,1728598439.546 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-10-10T22:13:59.975Z,1728598439.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-10-10T22:13:59.977Z,1728598439.977 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-10-10T22:14:00.218Z,1728598440.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-10-10T22:14:00.219Z,1728598440.219 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-10-10T22:14:00.778Z,1728598440.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-10-10T22:14:00.780Z,1728598440.780 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-10-10T22:14:00.928Z,1728598440.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-10-10T22:14:00.930Z,1728598440.930 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-10-10T22:14:01.022Z,1728598441.022 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-10-10T22:14:02.390Z,1728598442.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-10-10T22:14:02.391Z,1728598442.391 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-10-10T22:14:02.824Z,1728598442.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-10-10T22:14:02.825Z,1728598442.825 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-10-10T22:14:03.036Z,1728598443.036 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-10-10T22:14:03.037Z,1728598443.037 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-10-10T22:14:03.161Z,1728598443.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-10-10T22:14:03.161Z,1728598443.161 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-10-10T22:14:03.405Z,1728598443.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-10-10T22:14:03.407Z,1728598443.407 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-10-10T22:14:03.689Z,1728598443.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-10-10T22:14:03.691Z,1728598443.691 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-10-10T22:14:03.694Z,1728598443.694 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-10-10T22:14:03.798Z,1728598443.798 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-10-10T22:14:03.895Z,1728598443.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-10-10T22:14:04.011Z,1728598444.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-10-10T22:14:04.110Z,1728598444.110 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-10-10T22:14:04.226Z,1728598444.226 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-10-10T22:14:04.332Z,1728598444.332 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-10-10T22:14:04.462Z,1728598444.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-10-10T22:14:04.631Z,1728598444.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-10-10T22:14:04.795Z,1728598444.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-10-10T22:14:04.919Z,1728598444.919 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-10-10T22:14:05.195Z,1728598445.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-10-10T22:14:05.195Z,1728598445.195 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-10-10T22:14:05.212Z,1728598445.212 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-10-10T22:14:05.285Z,1728598445.285 [VerticalControl](DEBUG): Construct VerticalControl. 2024-10-10T22:14:05.345Z,1728598445.345 [VerticalControl] Loaded 2024-10-10T22:14:05.345Z,1728598445.345 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-10-10T22:14:05.348Z,1728598445.348 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-10-10T22:14:05.394Z,1728598445.394 [HorizontalControl] Loaded 2024-10-10T22:14:05.395Z,1728598445.395 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-10-10T22:14:05.397Z,1728598445.397 [SpeedControl](DEBUG): Construct SpeedControl. 2024-10-10T22:14:05.400Z,1728598445.400 [SpeedControl] Loaded 2024-10-10T22:14:05.400Z,1728598445.400 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-10-10T22:14:05.403Z,1728598445.403 [LoopControl](DEBUG): Construct LoopControl. 2024-10-10T22:14:05.403Z,1728598445.403 [LoopControl] Loaded 2024-10-10T22:14:05.404Z,1728598445.404 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-10-10T22:14:05.404Z,1728598445.404 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-10-10T22:14:05.406Z,1728598445.406 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-10-10T22:14:05.421Z,1728598445.421 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-10-10T22:14:05.423Z,1728598445.423 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-10-10T22:14:05.634Z,1728598445.634 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-10-10T22:14:05.634Z,1728598445.634 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-10-10T22:14:05.829Z,1728598445.829 [BuoyancyServo] Loaded 2024-10-10T22:14:05.829Z,1728598445.829 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-10-10T22:14:05.851Z,1728598445.851 [ElevatorServo] Loaded 2024-10-10T22:14:05.851Z,1728598445.851 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-10-10T22:14:05.871Z,1728598445.871 [MassServo] Loaded 2024-10-10T22:14:05.872Z,1728598445.872 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-10-10T22:14:05.892Z,1728598445.892 [RudderServo] Loaded 2024-10-10T22:14:05.892Z,1728598445.892 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-10-10T22:14:05.908Z,1728598445.908 [ThrusterHE] Loaded 2024-10-10T22:14:05.908Z,1728598445.908 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-10-10T22:14:05.909Z,1728598445.909 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-10-10T22:14:05.909Z,1728598445.909 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-10-10T22:14:06.046Z,1728598446.046 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-10-10T22:14:06.046Z,1728598446.046 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-10-10T22:14:06.060Z,1728598446.060 [NavChart] Loaded 2024-10-10T22:14:06.060Z,1728598446.060 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-10-10T22:14:06.065Z,1728598446.065 [UniversalFixResidualReporter] Loaded 2024-10-10T22:14:06.066Z,1728598446.066 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-10-10T22:14:06.066Z,1728598446.066 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-10-10T22:14:06.067Z,1728598446.067 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-10-10T22:14:06.166Z,1728598446.166 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-10-10T22:14:06.166Z,1728598446.166 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-10-10T22:14:06.964Z,1728598446.964 [AHRS_M2] Loaded 2024-10-10T22:14:06.965Z,1728598446.965 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-10-10T22:14:08.158Z,1728598448.158 [BPC1] Loaded 2024-10-10T22:14:08.158Z,1728598448.158 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-10-10T22:14:08.245Z,1728598448.245 [DATMMP] Loaded 2024-10-10T22:14:08.246Z,1728598448.246 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-10-10T22:14:08.247Z,1728598448.247 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4094C4E0 2024-10-10T22:14:08.247Z,1728598448.247 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 923 2024-10-10T22:14:08.387Z,1728598448.387 [DataOverHttps] Loaded 2024-10-10T22:14:08.388Z,1728598448.388 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-10-10T22:14:08.389Z,1728598448.389 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097C4E0 2024-10-10T22:14:08.389Z,1728598448.389 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924 2024-10-10T22:14:08.412Z,1728598448.412 [Depth_Keller] Loaded 2024-10-10T22:14:08.412Z,1728598448.412 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-10-10T22:14:08.417Z,1728598448.417 [DropWeight] Loaded 2024-10-10T22:14:08.417Z,1728598448.417 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-10-10T22:14:08.480Z,1728598448.480 [NAL9602] Loaded 2024-10-10T22:14:08.480Z,1728598448.480 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-10-10T22:14:08.510Z,1728598448.510 [Onboard] Loaded 2024-10-10T22:14:08.510Z,1728598448.510 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-10-10T22:14:08.511Z,1728598448.511 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AC4E0 2024-10-10T22:14:08.512Z,1728598448.512 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925 2024-10-10T22:14:08.518Z,1728598448.518 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-10-10T22:14:08.531Z,1728598448.531 [PowerOnly] Loaded 2024-10-10T22:14:08.531Z,1728598448.531 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-10-10T22:14:08.544Z,1728598448.544 [Power24vConverter] Loaded 2024-10-10T22:14:08.545Z,1728598448.545 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-10-10T22:14:08.560Z,1728598448.560 [Radio_Surface] Loaded 2024-10-10T22:14:08.560Z,1728598448.560 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-10-10T22:14:08.561Z,1728598448.561 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0 2024-10-10T22:14:08.561Z,1728598448.561 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926 2024-10-10T22:14:08.562Z,1728598448.562 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-10-10T22:14:08.563Z,1728598448.563 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-10-10T22:14:08.632Z,1728598448.632 [DepthRateCalculator] Loaded 2024-10-10T22:14:08.633Z,1728598448.633 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-10-10T22:14:08.638Z,1728598448.638 [PitchRateCalculator] Loaded 2024-10-10T22:14:08.638Z,1728598448.638 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-10-10T22:14:08.647Z,1728598448.647 [SpeedCalculator] Loaded 2024-10-10T22:14:08.647Z,1728598448.647 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-10-10T22:14:08.652Z,1728598448.652 [YawRateCalculator] Loaded 2024-10-10T22:14:08.652Z,1728598448.652 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-10-10T22:14:08.670Z,1728598448.670 [ElevatorOffsetCalculator] Loaded 2024-10-10T22:14:08.671Z,1728598448.671 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-10-10T22:14:08.671Z,1728598448.671 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-10-10T22:14:08.672Z,1728598448.672 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-10-10T22:14:08.895Z,1728598448.895 [CTD_Seabird] Loaded 2024-10-10T22:14:08.895Z,1728598448.895 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-10-10T22:14:08.896Z,1728598448.896 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A984E0 2024-10-10T22:14:08.897Z,1728598448.897 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927 2024-10-10T22:14:08.920Z,1728598448.920 [PAR_Licor] Loaded 2024-10-10T22:14:08.920Z,1728598448.920 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-10-10T22:14:08.963Z,1728598448.963 [WetLabsBB2FL] Loaded 2024-10-10T22:14:08.963Z,1728598448.963 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-10-10T22:14:08.964Z,1728598448.964 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC84E0 2024-10-10T22:14:08.965Z,1728598448.965 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928 2024-10-10T22:14:08.966Z,1728598448.966 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-10-10T22:14:08.966Z,1728598448.966 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-10-10T22:14:09.010Z,1728598449.010 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-10-10T22:14:09.011Z,1728598449.011 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-10-10T22:14:09.411Z,1728598449.411 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-10-10T22:14:09.412Z,1728598449.412 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-10-10T22:14:09.585Z,1728598449.585 [SBIT](DEBUG): Construct Startup Built In Test. 2024-10-10T22:14:09.594Z,1728598449.594 [SBIT] Loaded 2024-10-10T22:14:09.594Z,1728598449.594 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-10-10T22:14:09.597Z,1728598449.597 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-10-10T22:14:09.610Z,1728598449.610 [IBIT] Loaded 2024-10-10T22:14:09.610Z,1728598449.610 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-10-10T22:14:09.616Z,1728598449.616 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-10-10T22:14:09.721Z,1728598449.721 [CBIT] Loaded 2024-10-10T22:14:09.721Z,1728598449.721 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-10-10T22:14:09.721Z,1728598449.721 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-10-10T22:14:09.722Z,1728598449.722 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-10-10T22:14:09.866Z,1728598449.866 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-10-10T22:14:09.872Z,1728598449.872 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-10-10T22:14:09.875Z,1728598449.875 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-10-10T22:14:09.886Z,1728598449.886 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-10-10T22:14:09.887Z,1728598449.887 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-10-10T22:14:09.888Z,1728598449.888 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929 2024-10-10T22:14:09.892Z,1728598449.892 [Supervisor](INFO): Main Thread ID is 830 2024-10-10T22:14:09.892Z,1728598449.892 [Supervisor](DEBUG): Running supervisor. 2024-10-10T22:14:09.893Z,1728598449.893 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930 2024-10-10T22:14:09.893Z,1728598449.893 [CommandExec](INFO): Initializing the command executive. 2024-10-10T22:14:09.895Z,1728598449.895 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931 2024-10-10T22:14:09.897Z,1728598449.897 [controlThread ThreadHandler](INFO): Handler Thread ID is 932 2024-10-10T22:14:09.897Z,1728598449.897 [controlThread](DEBUG): Initializing ControlThread 2024-10-10T22:14:09.898Z,1728598449.898 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-10-10T22:14:09.900Z,1728598449.900 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-10-10T22:14:09.900Z,1728598449.900 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-10-10T22:14:09.901Z,1728598449.901 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-10-10T22:14:09.903Z,1728598449.903 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-10-10T22:14:09.903Z,1728598449.903 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-10-10T22:14:09.907Z,1728598449.907 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-10-10T22:14:09.907Z,1728598449.907 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-10-10T22:14:09.907Z,1728598449.907 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-10-10T22:14:09.908Z,1728598449.908 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-10-10T22:14:09.908Z,1728598449.908 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-10-10T22:14:09.913Z,1728598449.913 [SBIT](INFO): Initialize SBIT Component. 2024-10-10T22:14:09.914Z,1728598449.914 [SBIT](IMPORTANT): git: 2024-09-16_D 2024-10-10T22:14:09.914Z,1728598449.914 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92 2024-10-10T22:14:09.914Z,1728598449.914 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-10-10T22:14:09.915Z,1728598449.915 [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 2024-10-10T22:14:09.916Z,1728598449.916 [SBIT](INFO): Beginning SBIT in 27.000000 seconds. 2024-10-10T22:14:09.917Z,1728598449.917 [IBIT](INFO): Initialize IBIT Component. 2024-10-10T22:14:09.918Z,1728598449.918 [CBIT](DEBUG): Initialize CBIT Component. 2024-10-10T22:14:09.919Z,1728598449.919 [logger ThreadHandler](INFO): Handler Thread ID is 933 2024-10-10T22:14:09.929Z,1728598449.929 [CBIT](DEBUG): Initialized mux pins. 2024-10-10T22:14:09.929Z,1728598449.929 [CBIT](DEBUG): Initializing the watchdog timer. 2024-10-10T22:14:09.937Z,1728598449.937 [DATMMP ThreadHandler](INFO): Handler Thread ID is 934 2024-10-10T22:14:09.938Z,1728598449.938 [DATMMP](INFO): Start 2024-10-10T22:14:09.938Z,1728598449.938 [DATMMP](INFO): Powering up 2024-10-10T22:14:09.938Z,1728598449.938 [DATMMP](DEBUG): Initializing DATMMP. 2024-10-10T22:14:09.943Z,1728598449.943 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2024-10-10T22:14:09.944Z,1728598449.944 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-10-10T22:14:09.953Z,1728598449.953 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-10-10T22:14:09.954Z,1728598449.954 [CBIT](DEBUG): Initializing heartbeat. 2024-10-10T22:14:09.955Z,1728598449.955 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2024-10-10T22:14:09.975Z,1728598449.975 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2024-10-10T22:14:09.986Z,1728598449.986 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2024-10-10T22:14:09.987Z,1728598449.987 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-10-10T22:14:09.990Z,1728598449.990 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2024-10-10T22:14:09.994Z,1728598449.994 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2024-10-10T22:14:09.994Z,1728598449.994 [WetLabsBB2FL](INFO): Powering up 2024-10-10T22:14:10.003Z,1728598450.003 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-10-10T22:14:10.003Z,1728598450.003 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-10-10T22:14:10.003Z,1728598450.003 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-10-10T22:14:10.004Z,1728598450.004 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-10-10T22:14:10.004Z,1728598450.004 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-10-10T22:14:10.004Z,1728598450.004 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-10-10T22:14:10.004Z,1728598450.004 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-10-10T22:14:10.004Z,1728598450.004 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-10-10T22:14:10.025Z,1728598450.025 [CBIT](DEBUG): Deactivating GF circuits. 2024-10-10T22:14:10.025Z,1728598450.025 [CBIT](DEBUG): Deactivating emergency mode. 2024-10-10T22:14:10.065Z,1728598450.065 [CBIT](DEBUG): Backplane powered. 2024-10-10T22:14:10.066Z,1728598450.066 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-10-10T22:14:10.067Z,1728598450.067 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-10-10T22:14:10.067Z,1728598450.067 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-10-10T22:14:10.075Z,1728598450.075 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-10-10T22:14:10.136Z,1728598450.136 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-10-10T22:14:10.140Z,1728598450.140 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-10T22:14:10.188Z,1728598450.188 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-10-10T22:14:10.189Z,1728598450.189 [DATMMP](INFO): Starting 2024-10-10T22:14:10.201Z,1728598450.201 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-10-10T22:14:10.201Z,1728598450.201 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-10-10T22:14:10.207Z,1728598450.207 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-10-10T22:14:10.377Z,1728598450.377 [Radio_Surface](INFO): Powering up 2024-10-10T22:14:10.441Z,1728598450.441 [DATMMP](INFO): Starting 2024-10-10T22:14:10.487Z,1728598450.487 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-10-10T22:14:10.517Z,1728598450.517 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-10-10T22:14:10.518Z,1728598450.518 [Default:A.Wait](DEBUG): Construct Wait. 2024-10-10T22:14:10.521Z,1728598450.521 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-10T22:14:10.573Z,1728598450.573 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-10-10T22:14:10.575Z,1728598450.575 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-10-10T22:14:10.597Z,1728598450.597 [Default:E.Execute](DEBUG): Construct Execute. 2024-10-10T22:14:10.600Z,1728598450.600 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-10-10T22:14:10.620Z,1728598450.620 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-10-10T22:14:10.641Z,1728598450.641 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-10-10T22:14:10.693Z,1728598450.693 [DATMMP](INFO): Starting 2024-10-10T22:14:10.804Z,1728598450.804 [PowerOnly](INFO): Powering up loadControl 2024-10-10T22:14:10.822Z,1728598450.822 [Power24vConverter](INFO): Powering up. 2024-10-10T22:14:10.891Z,1728598450.891 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-10-10T22:14:10.897Z,1728598450.897 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-10-10T22:14:10.898Z,1728598450.898 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-10-10T22:14:10.905Z,1728598450.905 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-10-10T22:14:10.906Z,1728598450.906 [MassServo](DEBUG): Initializing EZServoServo. 2024-10-10T22:14:10.913Z,1728598450.913 [MassServo](DEBUG): Initializing MassServo. 2024-10-10T22:14:10.914Z,1728598450.914 [RudderServo](DEBUG): Initializing EZServoServo. 2024-10-10T22:14:10.921Z,1728598450.921 [RudderServo](DEBUG): Initializing RudderServo. 2024-10-10T22:14:10.922Z,1728598450.922 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-10-10T22:14:10.929Z,1728598450.929 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-10-10T22:14:10.946Z,1728598450.946 [DATMMP](INFO): Starting 2024-10-10T22:14:11.058Z,1728598451.058 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2024-10-10T22:14:11.058Z,1728598451.058 [DropWeight] Hardware Fault, FailCount= 1 2024-10-10T22:14:11.058Z,1728598451.058 [DropWeight](ERROR): Hardware Fault 2024-10-10T22:14:11.105Z,1728598451.105 [CommandExec](FAULT): Scheduling is paused 2024-10-10T22:14:11.113Z,1728598451.113 [CBIT](INFO): Critical error at 20241010T221411 2024-10-10T22:14:11.114Z,1728598451.114 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2024-10-10T22:14:11.116Z,1728598451.116 [CBIT](ERROR): Hardware Fault in component: DropWeight 2024-10-10T22:14:11.116Z,1728598451.116 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2024-10-10T22:14:11.197Z,1728598451.197 [DATMMP](INFO): Starting 2024-10-10T22:14:11.449Z,1728598451.449 [DATMMP](INFO): Starting 2024-10-10T22:14:11.459Z,1728598451.459 [CBIT](INFO): Critical error at 20241010T221411 2024-10-10T22:14:11.701Z,1728598451.701 [DATMMP](INFO): Starting 2024-10-10T22:14:11.954Z,1728598451.954 [DATMMP](INFO): Starting 2024-10-10T22:14:12.205Z,1728598452.205 [DATMMP](INFO): Starting 2024-10-10T22:14:12.457Z,1728598452.457 [DATMMP](INFO): Starting 2024-10-10T22:14:12.605Z,1728598452.605 [WetLabsBB2FL](INFO): Powering down 2024-10-10T22:14:12.709Z,1728598452.709 [DATMMP](INFO): Starting 2024-10-10T22:14:12.961Z,1728598452.961 [DATMMP](INFO): Starting 2024-10-10T22:14:13.213Z,1728598453.213 [DATMMP](INFO): Starting 2024-10-10T22:14:13.465Z,1728598453.465 [DATMMP](INFO): Starting 2024-10-10T22:14:13.717Z,1728598453.717 [DATMMP](INFO): Starting 2024-10-10T22:14:13.969Z,1728598453.969 [DATMMP](INFO): Starting 2024-10-10T22:14:14.221Z,1728598454.221 [DATMMP](INFO): Starting 2024-10-10T22:14:14.473Z,1728598454.473 [DATMMP](INFO): Starting 2024-10-10T22:14:14.725Z,1728598454.725 [DATMMP](INFO): Starting 2024-10-10T22:14:14.977Z,1728598454.977 [DATMMP](INFO): Starting 2024-10-10T22:14:15.229Z,1728598455.229 [DATMMP](INFO): Starting 2024-10-10T22:14:15.483Z,1728598455.483 [DATMMP](INFO): Starting 2024-10-10T22:14:15.733Z,1728598455.733 [DATMMP](INFO): Starting 2024-10-10T22:14:15.987Z,1728598455.987 [DATMMP](INFO): Starting 2024-10-10T22:14:16.237Z,1728598456.237 [DATMMP](INFO): Starting 2024-10-10T22:14:16.490Z,1728598456.490 [DATMMP](INFO): Starting 2024-10-10T22:14:16.574Z,1728598456.574 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-10-10T22:14:16.741Z,1728598456.741 [DATMMP](INFO): Starting 2024-10-10T22:14:16.993Z,1728598456.993 [DATMMP](INFO): Starting 2024-10-10T22:14:17.245Z,1728598457.245 [DATMMP](INFO): Starting 2024-10-10T22:14:17.497Z,1728598457.497 [DATMMP](INFO): Starting 2024-10-10T22:14:17.749Z,1728598457.749 [DATMMP](INFO): Starting 2024-10-10T22:14:18.001Z,1728598458.001 [DATMMP](INFO): Starting 2024-10-10T22:14:18.253Z,1728598458.253 [DATMMP](INFO): Starting 2024-10-10T22:14:18.505Z,1728598458.505 [DATMMP](INFO): Starting 2024-10-10T22:14:18.757Z,1728598458.757 [DATMMP](INFO): Starting 2024-10-10T22:14:19.009Z,1728598459.009 [DATMMP](INFO): Starting 2024-10-10T22:14:19.261Z,1728598459.261 [DATMMP](INFO): Starting 2024-10-10T22:14:19.513Z,1728598459.513 [DATMMP](INFO): Starting 2024-10-10T22:14:19.765Z,1728598459.765 [DATMMP](INFO): Starting 2024-10-10T22:14:20.017Z,1728598460.017 [DATMMP](INFO): Starting 2024-10-10T22:14:20.269Z,1728598460.269 [DATMMP](INFO): Starting 2024-10-10T22:14:20.521Z,1728598460.521 [DATMMP](INFO): Starting 2024-10-10T22:14:20.773Z,1728598460.773 [DATMMP](INFO): Starting 2024-10-10T22:14:21.025Z,1728598461.025 [DATMMP](INFO): Starting 2024-10-10T22:14:21.277Z,1728598461.277 [DATMMP](INFO): Starting 2024-10-10T22:14:21.530Z,1728598461.530 [DATMMP](INFO): Starting 2024-10-10T22:14:21.533Z,1728598461.533 [DATMMP](INFO): DAT read: 2024-10-10T22:14:21.534Z,1728598461.534 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-10-10T22:14:21.781Z,1728598461.781 [DATMMP](INFO): Starting 2024-10-10T22:14:22.033Z,1728598462.033 [DATMMP](INFO): Starting 2024-10-10T22:14:22.285Z,1728598462.285 [DATMMP](INFO): Starting 2024-10-10T22:14:22.537Z,1728598462.537 [DATMMP](INFO): Starting 2024-10-10T22:14:22.789Z,1728598462.789 [DATMMP](INFO): Starting 2024-10-10T22:14:23.041Z,1728598463.041 [DATMMP](INFO): Starting 2024-10-10T22:14:23.042Z,1728598463.042 [DATMMP](INFO): DAT read: MF Frequency Band 2024-10-10T22:14:23.043Z,1728598463.043 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-10-10T22:14:23.043Z,1728598463.043 [DATMMP](INFO): DAT read: Sep 10 2000 22:14:19 2024-10-10T22:14:23.293Z,1728598463.293 [DATMMP](INFO): Starting 2024-10-10T22:14:23.545Z,1728598463.545 [DATMMP](INFO): Starting 2024-10-10T22:14:23.797Z,1728598463.797 [DATMMP](INFO): Starting 2024-10-10T22:14:24.049Z,1728598464.049 [DATMMP](INFO): Starting 2024-10-10T22:14:24.301Z,1728598464.301 [DATMMP](INFO): Starting 2024-10-10T22:14:24.302Z,1728598464.302 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-10-10T22:14:24.303Z,1728598464.303 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-10-10T22:14:24.303Z,1728598464.303 [DATMMP](INFO): commRate: 1200 2024-10-10T22:14:24.553Z,1728598464.553 [DATMMP](INFO): Starting 2024-10-10T22:14:24.805Z,1728598464.805 [DATMMP](INFO): Starting 2024-10-10T22:14:25.057Z,1728598465.057 [DATMMP](INFO): Starting 2024-10-10T22:14:25.309Z,1728598465.309 [DATMMP](INFO): Starting 2024-10-10T22:14:25.561Z,1728598465.561 [DATMMP](INFO): Starting 2024-10-10T22:14:25.813Z,1728598465.813 [DATMMP](INFO): Starting 2024-10-10T22:14:26.065Z,1728598466.065 [DATMMP](INFO): Starting 2024-10-10T22:14:26.317Z,1728598466.317 [DATMMP](INFO): Starting 2024-10-10T22:14:26.369Z,1728598466.369 [DATMMP](INFO): entering command mode 2024-10-10T22:14:26.569Z,1728598466.569 [DATMMP](INFO): Starting 2024-10-10T22:14:26.569Z,1728598466.569 [DATMMP](INFO): DAT read: 2024-10-10T22:14:26.570Z,1728598466.570 [DATMMP](INFO): DAT read: user:1> 2024-10-10T22:14:26.570Z,1728598466.570 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-10-10T22:14:26.570Z,1728598466.570 [DATMMP](INFO): entering MMP mode 2024-10-10T22:14:26.821Z,1728598466.821 [DATMMP](INFO): Starting 2024-10-10T22:14:26.822Z,1728598466.822 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:26.823Z,1728598466.823 [DATMMP](INFO): status rx: x1 2024-10-10T22:14:26.824Z,1728598466.824 [DATMMP](INFO): MMP status: 1: started 2024-10-10T22:14:26.824Z,1728598466.824 [DATMMP](INFO): Received message type: status 2024-10-10T22:14:26.824Z,1728598466.824 [DATMMP](INFO): Handled 2024-10-10T22:14:27.073Z,1728598467.073 [DATMMP](INFO): Starting 2024-10-10T22:14:27.074Z,1728598467.074 [DATMMP](INFO): Sent Tx Power Config 8 2024-10-10T22:14:27.075Z,1728598467.075 [DATMMP](INFO): Setting local address: 8 2024-10-10T22:14:27.077Z,1728598467.077 [DATMMP](INFO): Setting time to: 22:14:27 And date to:10/10/2024 2024-10-10T22:14:27.325Z,1728598467.325 [DATMMP](INFO): Starting 2024-10-10T22:14:27.326Z,1728598467.326 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:27.326Z,1728598467.326 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-10-10T22:14:27.327Z,1728598467.327 [DATMMP](INFO): Received message type: mdmpower 2024-10-10T22:14:27.327Z,1728598467.327 [DATMMP](INFO): Handled 2024-10-10T22:14:27.577Z,1728598467.577 [DATMMP](INFO): Starting 2024-10-10T22:14:27.578Z,1728598467.578 [DATMMP](INFO): GSXN notify for xid: 10 2024-10-10T22:14:27.578Z,1728598467.578 [DATMMP](INFO): sreg_txpower rx: x8 2024-10-10T22:14:27.578Z,1728598467.578 [DATMMP](INFO): Received message type: sreg_txpower 2024-10-10T22:14:27.579Z,1728598467.579 [DATMMP](INFO): Match for : sreg_txpower 2024-10-10T22:14:27.579Z,1728598467.579 [DATMMP](INFO): ACK 2024-10-10T22:14:27.829Z,1728598467.829 [DATMMP](INFO): Starting 2024-10-10T22:14:27.830Z,1728598467.830 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:27.831Z,1728598467.831 [DATMMP](INFO): mdmlocal rx: x8 x0 x0 x0 2024-10-10T22:14:27.831Z,1728598467.831 [DATMMP](INFO): Received message type: mdmlocal 2024-10-10T22:14:27.831Z,1728598467.831 [DATMMP](INFO): Handled 2024-10-10T22:14:28.081Z,1728598468.081 [DATMMP](INFO): Starting 2024-10-10T22:14:28.082Z,1728598468.082 [DATMMP](INFO): GSXN notify for xid: 11 2024-10-10T22:14:28.082Z,1728598468.082 [DATMMP](INFO): sreg_locaddr rx: x8 2024-10-10T22:14:28.082Z,1728598468.082 [DATMMP](INFO): Received message type: sreg_locaddr 2024-10-10T22:14:28.083Z,1728598468.083 [DATMMP](INFO): Match for : sreg_locaddr 2024-10-10T22:14:28.083Z,1728598468.083 [DATMMP](INFO): ACK 2024-10-10T22:14:28.333Z,1728598468.333 [DATMMP](INFO): Starting 2024-10-10T22:14:28.334Z,1728598468.334 [DATMMP](INFO): GSXN notify for xid: 12 2024-10-10T22:14:28.335Z,1728598468.335 [DATMMP](INFO): timedate rx: x0 x16 xE x1B x9 xA x7C x0 2024-10-10T22:14:28.335Z,1728598468.335 [DATMMP](INFO): Received message type: timedate 2024-10-10T22:14:28.335Z,1728598468.335 [DATMMP](INFO): Match for : timedate 2024-10-10T22:14:28.335Z,1728598468.335 [DATMMP](INFO): ACK 2024-10-10T22:14:28.585Z,1728598468.585 [DATMMP](INFO): Starting 2024-10-10T22:14:28.586Z,1728598468.586 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:28.586Z,1728598468.586 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-10-10T22:14:28.587Z,1728598468.587 [DATMMP](INFO): Received message type: sys_verb 2024-10-10T22:14:28.587Z,1728598468.587 [DATMMP](INFO): Handled 2024-10-10T22:14:28.837Z,1728598468.837 [DATMMP](INFO): Starting 2024-10-10T22:14:28.838Z,1728598468.838 [DATMMP](INFO): GSXN notify for xid: 13 2024-10-10T22:14:28.838Z,1728598468.838 [DATMMP](INFO): sreg_verbosity rx: x0 2024-10-10T22:14:28.838Z,1728598468.838 [DATMMP](INFO): Received message type: sreg_verbosity 2024-10-10T22:14:28.838Z,1728598468.838 [DATMMP](INFO): Match for : sreg_verbosity 2024-10-10T22:14:28.839Z,1728598468.839 [DATMMP](INFO): ACK 2024-10-10T22:14:29.089Z,1728598469.089 [DATMMP](INFO): Starting 2024-10-10T22:14:29.090Z,1728598469.090 [DATMMP](INFO): GSXN notify for xid: 14 2024-10-10T22:14:29.090Z,1728598469.090 [DATMMP](INFO): version rx: x2 x5 2024-10-10T22:14:29.091Z,1728598469.091 [DATMMP](INFO): DatMMPVersion: 2.5 2024-10-10T22:14:29.091Z,1728598469.091 [DATMMP](INFO): Received message type: version 2024-10-10T22:14:29.091Z,1728598469.091 [DATMMP](INFO): Match for : version 2024-10-10T22:14:29.091Z,1728598469.091 [DATMMP](INFO): ACK 2024-10-10T22:14:29.091Z,1728598469.091 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-10-10T22:14:37.737Z,1728598477.737 [SBIT](IMPORTANT): Beginning Startup BIT 2024-10-10T22:14:37.749Z,1728598477.749 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-10T22:14:38.506Z,1728598478.506 [NAL9602](INFO): Powering up NAL9602 2024-10-10T22:14:42.446Z,1728598482.446 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:42.447Z,1728598482.447 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-10-10T22:14:42.447Z,1728598482.447 [DATMMP](INFO): Received message type: timesync 2024-10-10T22:14:42.447Z,1728598482.447 [DATMMP](INFO): Handled 2024-10-10T22:14:48.638Z,1728598488.638 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-10-10T22:14:49.082Z,1728598489.082 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-10T22:14:49.414Z,1728598489.414 [NAL9602](INFO): NAL9602 initialized 2024-10-10T22:14:52.278Z,1728598492.278 [DATMMP](INFO): GSXN notify for xid: 0 2024-10-10T22:14:52.278Z,1728598492.278 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-10-10T22:14:52.279Z,1728598492.279 [DATMMP](INFO): Received message type: timesync 2024-10-10T22:14:52.279Z,1728598492.279 [DATMMP](INFO): Handled 2024-10-10T22:15:00.039Z,1728598500.039 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008992 CHAN A1 (24V): 0.000633 CHAN A2 (12V): -0.002438 CHAN A3 (5V): -0.001654 CHAN B0 (3.3V): -0.000015 CHAN B1 (3.15aV): -0.000246 CHAN B2 (3.15bV): 0.000086 CHAN B3 (GND): -0.000076 OPEN: -0.000626 Full Scale: +/- 1 mA 2024-10-10T22:15:16.484Z,1728598516.484 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:15:29.810Z,1728598529.810 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:15:31.853Z,1728598531.853 [SBIT](IMPORTANT): SBIT PASSED 2024-10-10T22:15:31.853Z,1728598531.853 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-10-10T22:15:31.854Z,1728598531.854 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool; 2024-10-10T22:15:31.854Z,1728598531.854 [SBIT](IMPORTANT): CANONSampler.simulateHardware=1 bool; 2024-10-10T22:15:31.854Z,1728598531.854 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=246.619564 cubic_centimeter; 2024-10-10T22:15:31.855Z,1728598531.855 [SBIT](IMPORTANT): VerticalControl.massDefault=1.964576 millimeter; 2024-10-10T22:15:32.269Z,1728598532.269 [MissionManager](IMPORTANT): Started mission Startup 2024-10-10T22:15:32.269Z,1728598532.269 [Startup] Running Loop=1 2024-10-10T22:15:32.269Z,1728598532.269 [Startup](DEBUG): Aggregate::initialize Startup 2024-10-10T22:15:32.269Z,1728598532.269 [Startup:A.GoToSurface] Running Loop=1 2024-10-10T22:15:32.270Z,1728598532.270 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-10T22:15:32.270Z,1728598532.270 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-10T22:15:32.270Z,1728598532.270 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-10T22:15:32.271Z,1728598532.271 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-10T22:15:32.271Z,1728598532.271 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-10T22:15:32.271Z,1728598532.271 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-10T22:15:32.272Z,1728598532.272 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-10T22:15:32.274Z,1728598532.274 [Startup:StartupSatComms] Running Loop=1 2024-10-10T22:15:32.274Z,1728598532.274 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-10-10T22:15:32.274Z,1728598532.274 [Startup:StartupSatComms:A] Running Loop=1 2024-10-10T22:15:32.656Z,1728598532.656 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-10-10T22:15:43.143Z,1728598543.143 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:15:56.878Z,1728598556.878 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:16:10.210Z,1728598570.210 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:16:24.350Z,1728598584.350 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:16:32.479Z,1728598592.479 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-10T22:15:32.3Z 2024-10-10T22:16:32.479Z,1728598592.479 [Startup:StartupSatComms:A] Stopped 2024-10-10T22:16:32.480Z,1728598592.480 [Startup:StartupSatComms:B] Running Loop=1 2024-10-10T22:16:32.880Z,1728598592.880 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-10-10T22:16:38.055Z,1728598598.055 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004600 2024-10-10T22:16:38.178Z,1728598598.178 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:16:44.492Z,1728598604.492 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20241010T214848/Courier0007.lzma 2024-10-10T22:16:45.495Z,1728598605.495 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Courier0007.lzma.bak 2024-10-10T22:16:45.495Z,1728598605.495 [DataOverHttps](INFO): SBD MOMSN=23284544 2024-10-10T22:16:50.589Z,1728598610.589 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:17:01.225Z,1728598621.225 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20241010T221358/Courier0000.lzma 2024-10-10T22:17:02.226Z,1728598622.226 [DataOverHttps](INFO): Moved sent file to Logs/20241010T221358/Courier0000.lzma.bak 2024-10-10T22:17:02.227Z,1728598622.227 [DataOverHttps](INFO): SBD MOMSN=23284547 2024-10-10T22:17:03.921Z,1728598623.921 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:17:10.005Z,1728598630.005 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-10-10T22:17:10.005Z,1728598630.005 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-10T22:17:10.015Z,1728598630.015 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-10T22:17:10.453Z,1728598630.453 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-10T22:17:10.453Z,1728598630.453 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-10-10T22:17:17.933Z,1728598637.933 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20241010T214848/Express0008.lzma 2024-10-10T22:17:18.934Z,1728598638.934 [DataOverHttps](INFO): Moved sent file to Logs/20241010T214848/Express0008.lzma.bak 2024-10-10T22:17:18.934Z,1728598638.934 [DataOverHttps](INFO): SBD MOMSN=23284552 2024-10-10T22:17:32.647Z,1728598652.647 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-10T22:16:32.5Z 2024-10-10T22:17:32.647Z,1728598652.647 [Startup:StartupSatComms:B] Stopped 2024-10-10T22:17:32.647Z,1728598652.647 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-10-10T22:17:32.647Z,1728598652.647 [Startup:StartupSatComms] Stopped 2024-10-10T22:17:32.648Z,1728598652.648 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-10-10T22:17:32.653Z,1728598652.653 [Startup](INFO): Completed Startup 2024-10-10T22:17:32.653Z,1728598652.653 [MissionManager](INFO): Startup is completed. 2024-10-10T22:17:32.653Z,1728598652.653 [MissionManager](INFO): Uninitializing Mission Startup 2024-10-10T22:17:32.653Z,1728598652.653 [Startup] Stopped 2024-10-10T22:17:32.653Z,1728598652.653 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-10-10T22:17:32.653Z,1728598652.653 [Startup:A.GoToSurface] Stopped 2024-10-10T22:17:32.653Z,1728598652.653 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-10-10T22:17:33.043Z,1728598653.043 [MissionManager](IMPORTANT): Started mission Default 2024-10-10T22:17:33.043Z,1728598653.043 [Default] Running Loop=1 2024-10-10T22:17:33.043Z,1728598653.043 [Default](DEBUG): Aggregate::initialize Default 2024-10-10T22:17:33.043Z,1728598653.043 [Default:B.GoToSurface] Running Loop=1 2024-10-10T22:17:33.043Z,1728598653.043 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-10T22:17:33.043Z,1728598653.043 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-10T22:17:33.044Z,1728598653.044 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-10T22:17:33.044Z,1728598653.044 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-10T22:17:33.044Z,1728598653.044 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-10T22:17:33.065Z,1728598653.065 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-10T22:17:33.065Z,1728598653.065 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-10T22:17:33.065Z,1728598653.065 [Default:A.Wait] Running Loop=1 2024-10-10T22:17:33.066Z,1728598653.066 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-10-10T22:17:34.914Z,1728598654.914 [DataOverHttps](INFO): Sending 932 bytes from file Logs/20241010T221358/Express0001.lzma 2024-10-10T22:17:35.914Z,1728598655.914 [DataOverHttps](INFO): Moved sent file to Logs/20241010T221358/Express0001.lzma.bak 2024-10-10T22:17:35.915Z,1728598655.915 [DataOverHttps](INFO): SBD MOMSN=23284554 2024-10-10T22:17:46.387Z,1728598666.387 [Default:A.Wait](INFO): Done Waiting. 2024-10-10T22:17:46.387Z,1728598666.387 [Default:A.Wait] Stopped 2024-10-10T22:17:46.387Z,1728598666.387 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-10-10T22:17:46.823Z,1728598666.823 [Default:CheckIn] Running Loop=1 2024-10-10T22:17:46.823Z,1728598666.823 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-10T22:17:46.824Z,1728598666.824 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-10T22:17:47.172Z,1728598667.172 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-10-10T22:19:53.206Z,1728598793.206 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-10-10T22:20:11.017Z,1728598811.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-10-10T22:20:11.017Z,1728598811.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-10T22:20:11.073Z,1728598811.073 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-10T22:20:11.439Z,1728598811.439 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-10T22:20:11.439Z,1728598811.439 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-10-10T22:22:46.934Z,1728598966.934 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-10T22:17:46.8Z 2024-10-10T22:22:46.934Z,1728598966.934 [Default:CheckIn:Read_GPS] Stopped 2024-10-10T22:22:46.934Z,1728598966.934 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-10T22:22:47.339Z,1728598967.339 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-10-10T22:22:47.707Z,1728598967.707 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241010T221358/Courier0004.lzma 2024-10-10T22:22:48.546Z,1728598968.546 [DataOverHttps](INFO): Moved sent file to Logs/20241010T221358/Courier0004.lzma.bak 2024-10-10T22:22:48.547Z,1728598968.547 [DataOverHttps](INFO): SBD MOMSN=23284591 2024-10-10T22:23:04.096Z,1728598984.096 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20241010T221358/Express0005.lzma 2024-10-10T22:23:05.099Z,1728598985.099 [DataOverHttps](INFO): Moved sent file to Logs/20241010T221358/Express0005.lzma.bak 2024-10-10T22:23:05.099Z,1728598985.099 [DataOverHttps](INFO): SBD MOMSN=23284599 2024-10-10T22:23:06.735Z,1728598986.735 [Default:CheckIn:Read_Iridium] Stopped 2024-10-10T22:23:06.735Z,1728598986.735 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-10T22:23:06.735Z,1728598986.735 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-10T22:23:12.010Z,1728598992.010 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-10-10T22:23:12.010Z,1728598992.010 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-10T22:23:12.021Z,1728598992.021 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-10T22:23:12.430Z,1728598992.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-10T22:23:12.430Z,1728598992.430 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-10-10T22:24:30.746Z,1728599070.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:24:43.426Z,1728599083.426 [CommandExec](IMPORTANT): got command restart application 2024-10-10T22:24:43.677Z,1728599083.677 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-10-10T22:24:44.429Z,1728599084.429 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:44.429Z,1728599084.429 [CommandExec](INFO): Uninitializing the command executive. 2024-10-10T22:24:44.429Z,1728599084.429 [CommandExec](INFO): Uninitializing the command scheduler. 2024-10-10T22:24:44.429Z,1728599084.429 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.625Z,1728599084.625 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-10-10T22:24:44.625Z,1728599084.625 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-10-10T22:24:44.625Z,1728599084.625 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.626Z,1728599084.626 [NavChartDb](INFO): Join timeout helper Thread ID is 983 2024-10-10T22:24:44.713Z,1728599084.713 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:44.713Z,1728599084.713 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.727Z,1728599084.727 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-10-10T22:24:44.727Z,1728599084.727 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.728Z,1728599084.728 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 984 2024-10-10T22:24:44.929Z,1728599084.929 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:44.929Z,1728599084.929 [WetLabsBB2FL](INFO): Powering down 2024-10-10T22:24:44.930Z,1728599084.930 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.941Z,1728599084.941 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-10-10T22:24:44.941Z,1728599084.941 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:44.941Z,1728599084.941 [CTD_Seabird](INFO): Join timeout helper Thread ID is 985 2024-10-10T22:24:45.765Z,1728599085.765 [CTD_Seabird](INFO): Powering down 2024-10-10T22:24:45.777Z,1728599085.777 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:45.780Z,1728599085.780 [CTD_Seabird](INFO): Powering down 2024-10-10T22:24:45.794Z,1728599085.794 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:45.805Z,1728599085.805 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-10-10T22:24:45.805Z,1728599085.805 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:45.805Z,1728599085.805 [Radio_Surface](INFO): Join timeout helper Thread ID is 986 2024-10-10T22:24:45.837Z,1728599085.837 [Radio_Surface](INFO): Powering down 2024-10-10T22:24:45.838Z,1728599085.838 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:45.838Z,1728599085.838 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:45.845Z,1728599085.845 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-10-10T22:24:45.845Z,1728599085.845 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:45.845Z,1728599085.845 [Onboard](INFO): Join timeout helper Thread ID is 987 2024-10-10T22:24:46.341Z,1728599086.341 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-10-10T22:24:49.561Z,1728599089.561 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:49.561Z,1728599089.561 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:49.569Z,1728599089.569 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-10-10T22:24:49.570Z,1728599089.570 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:49.570Z,1728599089.570 [DataOverHttps](INFO): Join timeout helper Thread ID is 988 2024-10-10T22:24:50.357Z,1728599090.357 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:50.357Z,1728599090.357 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.365Z,1728599090.365 [ComponentRegistry](INFO): Shutting down DATMMP ThreadHandler 2024-10-10T22:24:50.365Z,1728599090.365 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.366Z,1728599090.366 [DATMMP](INFO): Join timeout helper Thread ID is 989 2024-10-10T22:24:50.453Z,1728599090.453 [DATMMP](INFO): Stop 2024-10-10T22:24:50.453Z,1728599090.453 [DATMMP](INFO): uninitialize 2024-10-10T22:24:50.453Z,1728599090.453 [DATMMP](INFO): Powering down 2024-10-10T22:24:50.526Z,1728599090.526 [DATMMP ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:50.526Z,1728599090.526 [DATMMP](INFO): uninitialize 2024-10-10T22:24:50.526Z,1728599090.526 [DATMMP](INFO): Powering down 2024-10-10T22:24:50.527Z,1728599090.527 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.554Z,1728599090.554 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-10-10T22:24:50.554Z,1728599090.554 [logger ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.554Z,1728599090.554 [logger](INFO): Join timeout helper Thread ID is 990 2024-10-10T22:24:50.577Z,1728599090.577 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:50.577Z,1728599090.577 [logger ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.581Z,1728599090.581 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-10-10T22:24:50.582Z,1728599090.582 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.582Z,1728599090.582 [CommandLine](INFO): Join timeout helper Thread ID is 991 2024-10-10T22:24:50.653Z,1728599090.653 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:50.653Z,1728599090.653 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.661Z,1728599090.661 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-10-10T22:24:50.661Z,1728599090.661 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.662Z,1728599090.662 [CommandExec](INFO): Join timeout helper Thread ID is 992 2024-10-10T22:24:50.663Z,1728599090.663 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-10-10T22:24:50.663Z,1728599090.663 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:50.664Z,1728599090.664 [controlThread](INFO): Join timeout helper Thread ID is 993 2024-10-10T22:24:50.941Z,1728599090.941 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-10-10T22:24:50.941Z,1728599090.941 [controlThread](DEBUG): Uninitializing ControlThread 2024-10-10T22:24:50.942Z,1728599090.942 [AHRS_M2](INFO): Powering down 2024-10-10T22:24:51.014Z,1728599091.014 [NAL9602](INFO): Powering down 2024-10-10T22:24:51.016Z,1728599091.016 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-10-10T22:24:51.017Z,1728599091.017 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-10-10T22:24:51.017Z,1728599091.017 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-10-10T22:24:51.018Z,1728599091.018 [MissionManager](INFO): Uninitializing Mission Default 2024-10-10T22:24:51.018Z,1728599091.018 [Default] Stopped 2024-10-10T22:24:51.018Z,1728599091.018 [Default](DEBUG): Aggregate::uninitialize Default 2024-10-10T22:24:51.018Z,1728599091.018 [Default:B.GoToSurface] Stopped 2024-10-10T22:24:51.018Z,1728599091.018 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-10-10T22:24:51.018Z,1728599091.018 [Default:CheckIn] Stopped 2024-10-10T22:24:51.018Z,1728599091.018 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-10T22:24:51.018Z,1728599091.018 [Default:CheckIn:C.Wait] Stopped 2024-10-10T22:24:51.018Z,1728599091.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-10T22:24:51.021Z,1728599091.021 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-10-10T22:24:51.022Z,1728599091.022 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-10-10T22:24:51.022Z,1728599091.022 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-10-10T22:24:51.022Z,1728599091.022 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-10-10T22:24:51.022Z,1728599091.022 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-10-10T22:24:51.023Z,1728599091.023 [BuoyancyServo](INFO): Powering down 2024-10-10T22:24:51.037Z,1728599091.037 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-10-10T22:24:51.037Z,1728599091.037 [ElevatorServo](INFO): Powering down 2024-10-10T22:24:51.038Z,1728599091.038 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-10-10T22:24:51.038Z,1728599091.038 [MassServo](INFO): Powering down 2024-10-10T22:24:51.039Z,1728599091.039 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-10-10T22:24:51.039Z,1728599091.039 [RudderServo](INFO): Powering down 2024-10-10T22:24:51.040Z,1728599091.040 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-10-10T22:24:51.040Z,1728599091.040 [ThrusterHE](INFO): Powering down 2024-10-10T22:24:51.041Z,1728599091.041 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-10-10T22:24:51.041Z,1728599091.041 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-10-10T22:24:51.041Z,1728599091.041 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-10-10T22:24:51.041Z,1728599091.041 [CBIT](DEBUG): Powering off loads. 2024-10-10T22:24:51.053Z,1728599091.053 [CBIT](DEBUG): Disabling WDT. 2024-10-10T22:24:51.065Z,1728599091.065 [CBIT](DEBUG): Opening all GF detection circuits. 2024-10-10T22:24:51.065Z,1728599091.065 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.120Z,1728599091.120 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.124Z,1728599091.124 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.130Z,1728599091.130 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.136Z,1728599091.136 [DATMMP ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.208Z,1728599091.208 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.213Z,1728599091.213 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.253Z,1728599091.253 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-10-10T22:24:51.317Z,1728599091.317 [logger ThreadHandler](INFO): Thread cancelled.