2025-07-23T22:18:19.576Z,1753309099.576 [Supervisor](DEBUG): Initializing supervisor. 2025-07-23T22:18:19.581Z,1753309099.581 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-23T22:18:19.581Z,1753309099.581 [SyncHandler](INFO): Protected caller Thread ID is 838 2025-07-23T22:18:19.581Z,1753309099.581 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-23T22:18:19.582Z,1753309099.582 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-23T22:18:19.583Z,1753309099.583 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839 2025-07-23T22:18:19.587Z,1753309099.587 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-23T22:18:19.607Z,1753309099.607 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-23T22:18:19.608Z,1753309099.608 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-23T22:18:19.608Z,1753309099.608 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840 2025-07-23T22:18:19.613Z,1753309099.613 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-23T22:18:19.614Z,1753309099.614 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-23T22:18:19.614Z,1753309099.614 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841 2025-07-23T22:18:19.616Z,1753309099.616 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-23T22:18:19.617Z,1753309099.617 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-23T22:18:19.618Z,1753309099.618 [logger ThreadHandler](INFO): Protected caller Thread ID is 842 2025-07-23T22:18:19.622Z,1753309099.622 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-23T22:18:19.622Z,1753309099.622 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-23T22:18:19.626Z,1753309099.626 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-23T22:18:20.128Z,1753309100.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-23T22:18:20.129Z,1753309100.129 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-23T22:18:20.353Z,1753309100.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-23T22:18:20.354Z,1753309100.354 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-23T22:18:20.455Z,1753309100.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-23T22:18:20.456Z,1753309100.456 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-23T22:18:20.718Z,1753309100.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-23T22:18:20.718Z,1753309100.718 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-23T22:18:20.809Z,1753309100.809 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-23T22:18:20.916Z,1753309100.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-23T22:18:20.916Z,1753309100.916 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-23T22:18:21.288Z,1753309101.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-23T22:18:21.289Z,1753309101.289 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-23T22:18:21.478Z,1753309101.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-23T22:18:21.479Z,1753309101.479 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-23T22:18:22.033Z,1753309102.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-23T22:18:22.035Z,1753309102.035 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-23T22:18:22.232Z,1753309102.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-23T22:18:22.232Z,1753309102.232 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-23T22:18:22.667Z,1753309102.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-23T22:18:22.667Z,1753309102.667 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-23T22:18:22.982Z,1753309102.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-23T22:18:22.983Z,1753309102.983 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-23T22:18:23.950Z,1753309103.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-23T22:18:23.951Z,1753309103.951 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-23T22:18:24.885Z,1753309104.885 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-23T22:18:24.886Z,1753309104.886 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-23T22:18:25.249Z,1753309105.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-23T22:18:25.250Z,1753309105.250 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-23T22:18:25.507Z,1753309105.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-23T22:18:25.509Z,1753309105.509 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2025-07-23T22:18:25.512Z,1753309105.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2025-07-23T22:18:25.642Z,1753309105.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2025-07-23T22:18:25.737Z,1753309105.737 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2025-07-23T22:18:25.884Z,1753309105.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2025-07-23T22:18:25.979Z,1753309105.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2025-07-23T22:18:26.262Z,1753309106.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-23T22:18:26.264Z,1753309106.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2025-07-23T22:18:26.379Z,1753309106.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2025-07-23T22:18:26.495Z,1753309106.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2025-07-23T22:18:26.605Z,1753309106.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2025-07-23T22:18:26.782Z,1753309106.782 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2025-07-23T22:18:26.891Z,1753309106.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2025-07-23T22:18:26.988Z,1753309106.988 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-07-23T22:18:27.000Z,1753309107.000 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-23T22:18:27.017Z,1753309107.017 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-23T22:18:27.019Z,1753309107.019 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-23T22:18:27.202Z,1753309107.202 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-23T22:18:27.202Z,1753309107.202 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-23T22:18:27.281Z,1753309107.281 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-23T22:18:27.344Z,1753309107.344 [VerticalControl] Loaded 2025-07-23T22:18:27.345Z,1753309107.345 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-23T22:18:27.347Z,1753309107.347 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-23T22:18:27.398Z,1753309107.398 [HorizontalControl] Loaded 2025-07-23T22:18:27.398Z,1753309107.398 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-23T22:18:27.400Z,1753309107.400 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-23T22:18:27.404Z,1753309107.404 [SpeedControl] Loaded 2025-07-23T22:18:27.404Z,1753309107.404 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-23T22:18:27.407Z,1753309107.407 [LoopControl](DEBUG): Construct LoopControl. 2025-07-23T22:18:27.407Z,1753309107.407 [LoopControl] Loaded 2025-07-23T22:18:27.407Z,1753309107.407 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-23T22:18:27.408Z,1753309107.408 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-23T22:18:27.409Z,1753309107.409 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-23T22:18:27.606Z,1753309107.606 [BuoyancyServo] Loaded 2025-07-23T22:18:27.607Z,1753309107.607 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-23T22:18:27.630Z,1753309107.630 [ElevatorServo] Loaded 2025-07-23T22:18:27.630Z,1753309107.630 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-23T22:18:27.653Z,1753309107.653 [MassServo] Loaded 2025-07-23T22:18:27.653Z,1753309107.653 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-23T22:18:27.674Z,1753309107.674 [RudderServo] Loaded 2025-07-23T22:18:27.674Z,1753309107.674 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-23T22:18:27.692Z,1753309107.692 [ThrusterHE] Loaded 2025-07-23T22:18:27.692Z,1753309107.692 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-07-23T22:18:27.693Z,1753309107.693 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-23T22:18:27.693Z,1753309107.693 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-23T22:18:27.768Z,1753309107.768 [DepthRateCalculator] Loaded 2025-07-23T22:18:27.768Z,1753309107.768 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-23T22:18:27.773Z,1753309107.773 [PitchRateCalculator] Loaded 2025-07-23T22:18:27.773Z,1753309107.773 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-23T22:18:27.784Z,1753309107.784 [SpeedCalculator] Loaded 2025-07-23T22:18:27.784Z,1753309107.784 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-23T22:18:27.788Z,1753309107.788 [YawRateCalculator] Loaded 2025-07-23T22:18:27.788Z,1753309107.788 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-23T22:18:27.808Z,1753309107.808 [ElevatorOffsetCalculator] Loaded 2025-07-23T22:18:27.808Z,1753309107.808 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-23T22:18:27.809Z,1753309107.809 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-23T22:18:27.809Z,1753309107.809 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-23T22:18:27.956Z,1753309107.956 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-23T22:18:27.957Z,1753309107.957 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-23T22:18:27.971Z,1753309107.971 [NavChart] Loaded 2025-07-23T22:18:27.972Z,1753309107.972 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-23T22:18:27.977Z,1753309107.977 [UniversalFixResidualReporter] Loaded 2025-07-23T22:18:27.977Z,1753309107.977 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-23T22:18:27.978Z,1753309107.978 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-23T22:18:27.979Z,1753309107.979 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-23T22:18:29.543Z,1753309109.543 [AHRS_M2] Loaded 2025-07-23T22:18:29.544Z,1753309109.544 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-23T22:18:29.798Z,1753309109.798 [BackseatComponent] Loaded 2025-07-23T22:18:29.799Z,1753309109.799 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-23T22:18:29.800Z,1753309109.800 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0 2025-07-23T22:18:29.800Z,1753309109.800 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2025-07-23T22:18:29.803Z,1753309109.803 [LcmUniversalReporter] Loaded 2025-07-23T22:18:29.803Z,1753309109.803 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-23T22:18:29.810Z,1753309109.810 [BioacousticsDataBridge] Loaded 2025-07-23T22:18:29.810Z,1753309109.810 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2025-07-23T22:18:30.690Z,1753309110.690 [BPC1] Loaded 2025-07-23T22:18:30.690Z,1753309110.690 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-23T22:18:30.793Z,1753309110.793 [DAT] Loaded 2025-07-23T22:18:30.793Z,1753309110.793 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-23T22:18:30.794Z,1753309110.794 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409594E0 2025-07-23T22:18:30.794Z,1753309110.794 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925 2025-07-23T22:18:30.937Z,1753309110.937 [DataOverHttps] Loaded 2025-07-23T22:18:30.938Z,1753309110.938 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-23T22:18:30.939Z,1753309110.939 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409894E0 2025-07-23T22:18:30.939Z,1753309110.939 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 2025-07-23T22:18:30.964Z,1753309110.964 [Depth_Keller] Loaded 2025-07-23T22:18:30.964Z,1753309110.964 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-07-23T22:18:30.970Z,1753309110.970 [DropWeight] Loaded 2025-07-23T22:18:30.970Z,1753309110.970 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-07-23T22:18:31.037Z,1753309111.037 [NAL9602] Loaded 2025-07-23T22:18:31.038Z,1753309111.038 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-23T22:18:31.069Z,1753309111.069 [Onboard] Loaded 2025-07-23T22:18:31.070Z,1753309111.070 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-23T22:18:31.071Z,1753309111.071 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0 2025-07-23T22:18:31.071Z,1753309111.071 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 2025-07-23T22:18:31.086Z,1753309111.086 [Power24vConverter] Loaded 2025-07-23T22:18:31.087Z,1753309111.087 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-23T22:18:31.105Z,1753309111.105 [Radio_Surface] Loaded 2025-07-23T22:18:31.105Z,1753309111.105 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-23T22:18:31.106Z,1753309111.106 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0 2025-07-23T22:18:31.107Z,1753309111.107 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928 2025-07-23T22:18:31.121Z,1753309111.121 [Sonardyne_Nano] Loaded 2025-07-23T22:18:31.121Z,1753309111.121 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-23T22:18:31.122Z,1753309111.122 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-23T22:18:31.122Z,1753309111.122 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-23T22:18:31.214Z,1753309111.214 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-23T22:18:31.216Z,1753309111.216 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-23T22:18:31.452Z,1753309111.452 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-23T22:18:31.453Z,1753309111.453 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-23T22:18:31.506Z,1753309111.506 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-23T22:18:31.507Z,1753309111.507 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-23T22:18:31.741Z,1753309111.741 [CTD_Seabird] Loaded 2025-07-23T22:18:31.741Z,1753309111.741 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-23T22:18:31.742Z,1753309111.742 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0 2025-07-23T22:18:31.743Z,1753309111.743 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929 2025-07-23T22:18:31.766Z,1753309111.766 [PAR_Licor] Loaded 2025-07-23T22:18:31.766Z,1753309111.766 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-07-23T22:18:31.813Z,1753309111.813 [WetLabsBB2FL] Loaded 2025-07-23T22:18:31.814Z,1753309111.814 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-07-23T22:18:31.815Z,1753309111.815 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B614E0 2025-07-23T22:18:31.815Z,1753309111.815 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930 2025-07-23T22:18:31.816Z,1753309111.816 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-23T22:18:31.817Z,1753309111.817 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-23T22:18:32.252Z,1753309112.252 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-23T22:18:32.253Z,1753309112.253 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-23T22:18:32.463Z,1753309112.463 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-23T22:18:32.472Z,1753309112.472 [SBIT] Loaded 2025-07-23T22:18:32.472Z,1753309112.472 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-23T22:18:32.475Z,1753309112.475 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-23T22:18:32.489Z,1753309112.489 [IBIT] Loaded 2025-07-23T22:18:32.489Z,1753309112.489 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-23T22:18:32.495Z,1753309112.495 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-23T22:18:32.579Z,1753309112.579 [CBIT] Loaded 2025-07-23T22:18:32.579Z,1753309112.579 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-23T22:18:32.613Z,1753309112.613 [GFScanner] Loaded 2025-07-23T22:18:32.614Z,1753309112.614 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-23T22:18:32.614Z,1753309112.614 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-23T22:18:32.621Z,1753309112.621 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-23T22:18:32.624Z,1753309112.624 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-23T22:18:32.635Z,1753309112.635 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-23T22:18:32.636Z,1753309112.636 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-07-23T22:18:32.637Z,1753309112.637 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2025-07-23T22:18:32.641Z,1753309112.641 [Supervisor](INFO): Main Thread ID is 831 2025-07-23T22:18:32.641Z,1753309112.641 [Supervisor](DEBUG): Running supervisor. 2025-07-23T22:18:32.642Z,1753309112.642 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2025-07-23T22:18:32.642Z,1753309112.642 [CommandExec](INFO): Initializing the command executive. 2025-07-23T22:18:32.644Z,1753309112.644 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2025-07-23T22:18:32.646Z,1753309112.646 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2025-07-23T22:18:32.647Z,1753309112.647 [controlThread](DEBUG): Initializing ControlThread 2025-07-23T22:18:32.647Z,1753309112.647 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-23T22:18:32.649Z,1753309112.649 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-23T22:18:32.650Z,1753309112.650 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-23T22:18:32.650Z,1753309112.650 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-23T22:18:32.652Z,1753309112.652 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-23T22:18:32.652Z,1753309112.652 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-23T22:18:32.652Z,1753309112.652 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-23T22:18:32.653Z,1753309112.653 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-23T22:18:32.653Z,1753309112.653 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-23T22:18:32.654Z,1753309112.654 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-23T22:18:32.655Z,1753309112.655 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-23T22:18:32.662Z,1753309112.662 [SBIT](INFO): Initialize SBIT Component. 2025-07-23T22:18:32.663Z,1753309112.663 [SBIT](IMPORTANT): git: 2025-07-16 2025-07-23T22:18:32.663Z,1753309112.663 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81 2025-07-23T22:18:32.663Z,1753309112.663 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-23T22:18:32.664Z,1753309112.664 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-07-23T22:18:32.666Z,1753309112.666 [SBIT](INFO): Beginning SBIT in 26.000000 seconds. 2025-07-23T22:18:32.666Z,1753309112.666 [IBIT](INFO): Initialize IBIT Component. 2025-07-23T22:18:32.667Z,1753309112.667 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-23T22:18:32.668Z,1753309112.668 [logger ThreadHandler](INFO): Handler Thread ID is 935 2025-07-23T22:18:32.678Z,1753309112.678 [CBIT](DEBUG): Initialized mux pins. 2025-07-23T22:18:32.678Z,1753309112.678 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-23T22:18:32.687Z,1753309112.687 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 2025-07-23T22:18:32.699Z,1753309112.699 [DAT ThreadHandler](INFO): Handler Thread ID is 937 2025-07-23T22:18:32.699Z,1753309112.699 [DAT](INFO): Powering up 2025-07-23T22:18:32.700Z,1753309112.700 [DAT](DEBUG): Initializing DAT. 2025-07-23T22:18:32.702Z,1753309112.702 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-07-23T22:18:32.703Z,1753309112.703 [CBIT](DEBUG): Initializing heartbeat. 2025-07-23T22:18:32.705Z,1753309112.705 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939 2025-07-23T22:18:32.706Z,1753309112.706 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-23T22:18:32.715Z,1753309112.715 [Onboard ThreadHandler](INFO): Handler Thread ID is 940 2025-07-23T22:18:32.732Z,1753309112.732 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941 2025-07-23T22:18:32.759Z,1753309112.759 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942 2025-07-23T22:18:32.759Z,1753309112.759 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-23T22:18:32.764Z,1753309112.764 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944 2025-07-23T22:18:32.767Z,1753309112.767 [WetLabsBB2FL](INFO): Powering up 2025-07-23T22:18:32.768Z,1753309112.768 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946 2025-07-23T22:18:32.774Z,1753309112.774 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-23T22:18:32.776Z,1753309112.776 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000 2025-07-23T22:18:32.777Z,1753309112.777 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000 2025-07-23T22:18:32.778Z,1753309112.778 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000 2025-07-23T22:18:32.778Z,1753309112.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000 2025-07-23T22:18:32.778Z,1753309112.778 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000 2025-07-23T22:18:32.778Z,1753309112.778 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000 2025-07-23T22:18:32.779Z,1753309112.779 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000 2025-07-23T22:18:32.780Z,1753309112.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000 2025-07-23T22:18:32.780Z,1753309112.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000 2025-07-23T22:18:32.780Z,1753309112.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000 2025-07-23T22:18:32.814Z,1753309112.814 [CBIT](DEBUG): Backplane powered. 2025-07-23T22:18:32.815Z,1753309112.815 [GFScanner](DEBUG): Initializing GFScanner 2025-07-23T22:18:32.815Z,1753309112.815 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-23T22:18:32.819Z,1753309112.819 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-23T22:18:32.820Z,1753309112.820 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-07-23T22:18:32.820Z,1753309112.820 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-07-23T22:18:32.825Z,1753309112.825 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-07-23T22:18:32.898Z,1753309112.898 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-07-23T22:18:32.903Z,1753309112.903 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-23T22:18:32.947Z,1753309112.947 [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 } } } 2025-07-23T22:18:32.948Z,1753309112.948 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-07-23T22:18:32.948Z,1753309112.948 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-07-23T22:18:32.952Z,1753309112.952 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-07-23T22:18:33.138Z,1753309113.138 [Radio_Surface](INFO): Powering up 2025-07-23T22:18:33.244Z,1753309113.244 [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 2025-07-23T22:18:33.263Z,1753309113.263 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-23T22:18:33.265Z,1753309113.265 [Default:A.Wait](DEBUG): Construct Wait. 2025-07-23T22:18:33.268Z,1753309113.268 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-23T22:18:33.313Z,1753309113.313 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-23T22:18:33.324Z,1753309113.324 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-23T22:18:33.342Z,1753309113.342 [Default:E.Execute](DEBUG): Construct Execute. 2025-07-23T22:18:33.345Z,1753309113.345 [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" } } 2025-07-23T22:18:33.350Z,1753309113.350 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-07-23T22:18:33.367Z,1753309113.367 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-23T22:18:33.515Z,1753309113.515 [Depth_Keller](INFO): Initializing. 2025-07-23T22:18:33.516Z,1753309113.516 [Power24vConverter](INFO): Powering up. 2025-07-23T22:18:33.517Z,1753309113.517 [Sonardyne_Nano](INFO): Initializing. 2025-07-23T22:18:33.554Z,1753309113.554 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-23T22:18:33.581Z,1753309113.581 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-23T22:18:33.590Z,1753309113.590 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-23T22:18:33.592Z,1753309113.592 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-23T22:18:33.599Z,1753309113.599 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-23T22:18:33.599Z,1753309113.599 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-23T22:18:33.607Z,1753309113.607 [MassServo](DEBUG): Initializing MassServo. 2025-07-23T22:18:33.607Z,1753309113.607 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-23T22:18:33.615Z,1753309113.615 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-23T22:18:33.615Z,1753309113.615 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-07-23T22:18:33.623Z,1753309113.623 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-07-23T22:18:35.379Z,1753309115.379 [WetLabsBB2FL](INFO): Powering down 2025-07-23T22:18:35.785Z,1753309115.785 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-23T22:18:37.028Z,1753309117.028 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2025-07-23T22:18:39.291Z,1753309119.291 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-07-23T22:18:39.791Z,1753309119.791 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-07-23T22:18:45.055Z,1753309125.055 [DAT](INFO): DAT read: 2025-07-23T22:18:45.056Z,1753309125.056 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-23T22:18:46.819Z,1753309126.819 [DAT](INFO): DAT read: MF Frequency Band 2025-07-23T22:18:46.821Z,1753309126.821 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-07-23T22:18:46.821Z,1753309126.821 [DAT](INFO): DAT read: Jul 23 2025 22:18:34 2025-07-23T22:18:48.080Z,1753309128.080 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-23T22:18:48.081Z,1753309128.081 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-07-23T22:18:48.081Z,1753309128.081 [DAT](INFO): commRate: 800 2025-07-23T22:18:50.146Z,1753309130.146 [DAT](INFO): entering command mode 2025-07-23T22:18:50.347Z,1753309130.347 [DAT](INFO): DAT read: 2025-07-23T22:18:50.347Z,1753309130.347 [DAT](INFO): DAT read: user:1> 2025-07-23T22:18:50.348Z,1753309130.348 [DAT](INFO): setting verbose to 3 2025-07-23T22:18:50.599Z,1753309130.599 [DAT](INFO): DAT read: user:1> 2025-07-23T22:18:50.600Z,1753309130.600 [DAT](INFO): DAT read: Verbose | 3 2025-07-23T22:18:50.600Z,1753309130.600 [DAT](INFO): set verbose to 3 2025-07-23T22:18:50.601Z,1753309130.601 [DAT](INFO): setting DatVerbose to 27440 2025-07-23T22:18:50.851Z,1753309130.851 [DAT](INFO): DAT read: user:2> 2025-07-23T22:18:50.852Z,1753309130.852 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-23T22:18:50.852Z,1753309130.852 [DAT](INFO): set DatVerbose to 27440 2025-07-23T22:18:50.853Z,1753309130.853 [DAT](INFO): setting transmit power to 8 2025-07-23T22:18:51.103Z,1753309131.103 [DAT](INFO): DAT read: user:3> 2025-07-23T22:18:51.104Z,1753309131.104 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-23T22:18:51.104Z,1753309131.104 [DAT](INFO): set transmit power to 8 2025-07-23T22:18:51.105Z,1753309131.105 [DAT](INFO): setting local address to 12 2025-07-23T22:18:51.355Z,1753309131.355 [DAT](INFO): DAT read: user:4> 2025-07-23T22:18:51.356Z,1753309131.356 [DAT](INFO): DAT read: LocalAddr | 12 2025-07-23T22:18:51.356Z,1753309131.356 [DAT](INFO): set local address to 12 2025-07-23T22:18:51.357Z,1753309131.357 [DAT](INFO): Setting time to: 22:18:51 And date to:7/23/2025 2025-07-23T22:18:51.607Z,1753309131.607 [DAT](INFO): DAT read: user:5> 2025-07-23T22:18:51.608Z,1753309131.608 [DAT](INFO): DAT read: Wed Jul 23, 2025 22:18:51 2025-07-23T22:18:51.608Z,1753309131.608 [DAT](INFO): Local DAT time set to Wed Jul 23, 2025 22:18:51 2025-07-23T22:18:59.241Z,1753309139.241 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-23T22:18:59.246Z,1753309139.246 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T22:19:01.244Z,1753309141.244 [NAL9602](INFO): Powering up NAL9602 2025-07-23T22:19:03.755Z,1753309143.755 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.001959 CHAN A1 (24V): -0.007825 CHAN A2 (12V): -0.004993 CHAN A3 (5V): -0.003455 CHAN B0 (3.3V): -0.001240 CHAN B1 (3.15aV): -0.001655 CHAN B2 (3.15bV): -0.001478 CHAN B3 (GND): -0.000315 OPEN: 0.004288 Full Scale: +/- 1 mA 2025-07-23T22:19:12.152Z,1753309152.152 [NAL9602](INFO): NAL9602 initialized 2025-07-23T22:19:40.849Z,1753309180.849 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:19:49.369Z,1753309189.369 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:19:53.437Z,1753309193.437 [SBIT](IMPORTANT): SBIT PASSED 2025-07-23T22:19:53.437Z,1753309193.437 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-23T22:19:53.438Z,1753309193.438 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-07-23T22:19:53.440Z,1753309193.440 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=245.648064 cubic_centimeter; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.746382 millimeter; 2025-07-23T22:19:53.441Z,1753309193.441 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-07-23T22:19:53.828Z,1753309193.828 [MissionManager](IMPORTANT): Started mission Startup 2025-07-23T22:19:53.829Z,1753309193.829 [Startup] Running Loop=1 2025-07-23T22:19:53.829Z,1753309193.829 [Startup](DEBUG): Aggregate::initialize Startup 2025-07-23T22:19:53.829Z,1753309193.829 [Startup:A.GoToSurface] Running Loop=1 2025-07-23T22:19:53.829Z,1753309193.829 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-23T22:19:53.829Z,1753309193.829 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-23T22:19:53.830Z,1753309193.830 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-23T22:19:53.830Z,1753309193.830 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-23T22:19:53.831Z,1753309193.831 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-23T22:19:53.831Z,1753309193.831 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-23T22:19:53.831Z,1753309193.831 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-23T22:19:53.833Z,1753309193.833 [Startup:StartupSatComms] Running Loop=1 2025-07-23T22:19:53.833Z,1753309193.833 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-07-23T22:19:53.833Z,1753309193.833 [Startup:StartupSatComms:A] Running Loop=1 2025-07-23T22:19:54.232Z,1753309194.232 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-07-23T22:19:56.238Z,1753309196.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:04.318Z,1753309204.318 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:11.590Z,1753309211.590 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:13.717Z,1753309213.717 [CommandExec](IMPORTANT): got command strobe off 2025-07-23T22:20:13.717Z,1753309213.717 [CommandExec](IMPORTANT): Deactivating strobe 2025-07-23T22:20:17.043Z,1753309217.043 [CommandExec](IMPORTANT): got command gfscan 2025-07-23T22:20:17.278Z,1753309217.278 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T22:20:19.264Z,1753309219.264 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:21.523Z,1753309221.523 [CommandExec](IMPORTANT): got command failComponent 2025-07-23T22:20:21.523Z,1753309221.523 [CommandExec](IMPORTANT): Failed components: 2025-07-23T22:20:21.523Z,1753309221.523 [CommandExec](IMPORTANT): No failed Components. 2025-07-23T22:20:21.717Z,1753309221.717 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.003616 CHAN A1 (24V): -0.008160 CHAN A2 (12V): -0.005164 CHAN A3 (5V): -0.003510 CHAN B0 (3.3V): -0.000501 CHAN B1 (3.15aV): -0.001088 CHAN B2 (3.15bV): -0.000725 CHAN B3 (GND): -0.000713 OPEN: 0.004515 Full Scale: +/- 1 mA 2025-07-23T22:20:26.943Z,1753309226.943 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:34.216Z,1753309234.216 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:42.293Z,1753309242.293 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:49.458Z,1753309249.458 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch 2025-07-23T22:20:49.459Z,1753309249.459 [CommandExec](IMPORTANT): Onboard.Pressure 9.748753 psi 2025-07-23T22:20:49.565Z,1753309249.565 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:54.024Z,1753309254.024 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-23T22:19:53.8Z 2025-07-23T22:20:54.024Z,1753309254.024 [Startup:StartupSatComms:A] Stopped 2025-07-23T22:20:54.024Z,1753309254.024 [Startup:StartupSatComms:B] Running Loop=1 2025-07-23T22:20:54.433Z,1753309254.433 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-23T22:20:57.644Z,1753309257.644 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:21:01.037Z,1753309261.037 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004811 2025-07-23T22:20:58.657Z,1753309258.657 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:20:59.088Z,1753309259.088 [CommandExec](IMPORTANT): got command get Onboard.Humidity 2025-07-23T22:20:59.089Z,1753309259.089 [CommandExec](IMPORTANT): Onboard.Humidity 18.679037 % 2025-07-23T22:21:02.839Z,1753309262.839 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250723T221411/Courier0000.lzma 2025-07-23T22:21:03.840Z,1753309263.840 [DataOverHttps](INFO): Moved sent file to Logs/20250723T221411/Courier0000.lzma.bak 2025-07-23T22:21:03.840Z,1753309263.840 [DataOverHttps](INFO): SBD MOMSN=25579711 2025-07-23T22:21:06.579Z,1753309266.579 [CommandExec](IMPORTANT): got command failComponent 2025-07-23T22:21:06.579Z,1753309266.579 [CommandExec](IMPORTANT): Failed components: 2025-07-23T22:21:06.579Z,1753309266.579 [CommandExec](IMPORTANT): No failed Components. 2025-07-23T22:21:06.747Z,1753309266.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:21:11.155Z,1753309271.155 [CommandExec](IMPORTANT): got command gfscan 2025-07-23T22:21:11.201Z,1753309271.201 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-23T22:21:13.604Z,1753309273.604 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:21:15.708Z,1753309275.708 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002336 CHAN A1 (24V): -0.007693 CHAN A2 (12V): -0.005163 CHAN A3 (5V): -0.003325 CHAN B0 (3.3V): -0.001405 CHAN B1 (3.15aV): -0.001214 CHAN B2 (3.15bV): -0.000973 CHAN B3 (GND): -0.000259 OPEN: 0.004637 Full Scale: +/- 1 mA 2025-07-23T22:21:19.743Z,1753309279.743 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250723T221411/Courier0004.lzma 2025-07-23T22:21:20.744Z,1753309280.744 [DataOverHttps](INFO): Moved sent file to Logs/20250723T221411/Courier0004.lzma.bak 2025-07-23T22:21:20.744Z,1753309280.744 [DataOverHttps](INFO): SBD MOMSN=25579714 2025-07-23T22:21:22.089Z,1753309282.089 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-23T22:21:25.535Z,1753309285.535 [CommandExec](IMPORTANT): got command quit 2025-07-23T22:21:26.538Z,1753309286.538 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-07-23T22:21:26.539Z,1753309286.539 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:26.539Z,1753309286.539 [CommandExec](INFO): Uninitializing the command executive. 2025-07-23T22:21:26.539Z,1753309286.539 [CommandExec](INFO): Uninitializing the command scheduler. 2025-07-23T22:21:26.539Z,1753309286.539 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:26.638Z,1753309286.638 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-07-23T22:21:26.638Z,1753309286.638 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-07-23T22:21:26.639Z,1753309286.639 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:26.639Z,1753309286.639 [NavChartDb](INFO): Join timeout helper Thread ID is 975 2025-07-23T22:21:27.030Z,1753309287.030 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:27.031Z,1753309287.031 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:27.038Z,1753309287.038 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-07-23T22:21:27.038Z,1753309287.038 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:27.039Z,1753309287.039 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 976 2025-07-23T22:21:27.518Z,1753309287.518 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:27.519Z,1753309287.519 [WetLabsBB2FL](INFO): Powering down 2025-07-23T22:21:27.519Z,1753309287.519 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:27.530Z,1753309287.530 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-07-23T22:21:27.530Z,1753309287.530 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:27.531Z,1753309287.531 [CTD_Seabird](INFO): Join timeout helper Thread ID is 977 2025-07-23T22:21:28.466Z,1753309288.466 [CTD_Seabird](INFO): Powering down 2025-07-23T22:21:28.478Z,1753309288.478 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:28.478Z,1753309288.478 [CTD_Seabird](INFO): Powering down 2025-07-23T22:21:28.491Z,1753309288.491 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:28.506Z,1753309288.506 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-07-23T22:21:28.506Z,1753309288.506 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:28.520Z,1753309288.520 [Radio_Surface](INFO): Join timeout helper Thread ID is 978 2025-07-23T22:21:28.695Z,1753309288.695 [Radio_Surface](INFO): Powering down 2025-07-23T22:21:28.696Z,1753309288.696 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:28.696Z,1753309288.696 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:28.702Z,1753309288.702 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-07-23T22:21:28.702Z,1753309288.702 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:28.703Z,1753309288.703 [Onboard](INFO): Join timeout helper Thread ID is 979 2025-07-23T22:21:28.956Z,1753309288.956 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2025-07-23T22:21:30.187Z,1753309290.187 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-07-23T22:21:31.602Z,1753309291.602 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:31.603Z,1753309291.603 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:31.617Z,1753309291.617 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-07-23T22:21:31.617Z,1753309291.617 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:31.617Z,1753309291.617 [DataOverHttps](INFO): Join timeout helper Thread ID is 980 2025-07-23T22:21:32.194Z,1753309292.194 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.194Z,1753309292.194 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.207Z,1753309292.207 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-07-23T22:21:32.207Z,1753309292.207 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.208Z,1753309292.208 [DAT](INFO): Join timeout helper Thread ID is 981 2025-07-23T22:21:32.302Z,1753309292.302 [DAT](INFO): Powering down 2025-07-23T22:21:32.375Z,1753309292.375 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.375Z,1753309292.375 [DAT](INFO): Powering down 2025-07-23T22:21:32.376Z,1753309292.376 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.395Z,1753309292.395 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-07-23T22:21:32.395Z,1753309292.395 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.395Z,1753309292.395 [BackseatComponent](INFO): Join timeout helper Thread ID is 982 2025-07-23T22:21:32.455Z,1753309292.455 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.455Z,1753309292.455 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.475Z,1753309292.475 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-07-23T22:21:32.475Z,1753309292.475 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.475Z,1753309292.475 [logger](INFO): Join timeout helper Thread ID is 983 2025-07-23T22:21:32.498Z,1753309292.498 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.499Z,1753309292.499 [logger ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.515Z,1753309292.515 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-07-23T22:21:32.515Z,1753309292.515 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.515Z,1753309292.515 [CommandLine](INFO): Join timeout helper Thread ID is 984 2025-07-23T22:21:32.594Z,1753309292.594 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.595Z,1753309292.595 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.615Z,1753309292.615 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-07-23T22:21:32.615Z,1753309292.615 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.615Z,1753309292.615 [CommandExec](INFO): Join timeout helper Thread ID is 985 2025-07-23T22:21:32.618Z,1753309292.618 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-07-23T22:21:32.618Z,1753309292.618 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:32.619Z,1753309292.619 [controlThread](INFO): Join timeout helper Thread ID is 986 2025-07-23T22:21:32.986Z,1753309292.986 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-07-23T22:21:32.987Z,1753309292.987 [controlThread](DEBUG): Uninitializing ControlThread 2025-07-23T22:21:32.987Z,1753309292.987 [AHRS_M2](INFO): Powering down 2025-07-23T22:21:33.066Z,1753309293.066 [NAL9602](INFO): Powering down 2025-07-23T22:21:33.068Z,1753309293.068 [Sonardyne_Nano](INFO): Powering down 2025-07-23T22:21:33.275Z,1753309293.275 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-07-23T22:21:33.275Z,1753309293.275 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-07-23T22:21:33.276Z,1753309293.276 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-07-23T22:21:33.276Z,1753309293.276 [MissionManager](INFO): Uninitializing Mission Default 2025-07-23T22:21:33.279Z,1753309293.279 [MissionManager](INFO): Uninitializing Mission Startup 2025-07-23T22:21:33.279Z,1753309293.279 [Startup] Stopped 2025-07-23T22:21:33.279Z,1753309293.279 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-07-23T22:21:33.279Z,1753309293.279 [Startup:A.GoToSurface] Stopped 2025-07-23T22:21:33.279Z,1753309293.279 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-23T22:21:33.279Z,1753309293.279 [Startup:StartupSatComms] Stopped 2025-07-23T22:21:33.279Z,1753309293.279 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-07-23T22:21:33.280Z,1753309293.280 [Startup:StartupSatComms:B] Stopped 2025-07-23T22:21:33.281Z,1753309293.281 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-07-23T22:21:33.281Z,1753309293.281 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-07-23T22:21:33.282Z,1753309293.282 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-07-23T22:21:33.282Z,1753309293.282 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-07-23T22:21:33.282Z,1753309293.282 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-07-23T22:21:33.283Z,1753309293.283 [BuoyancyServo](INFO): Powering down 2025-07-23T22:21:33.294Z,1753309293.294 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-07-23T22:21:33.294Z,1753309293.294 [ElevatorServo](INFO): Powering down 2025-07-23T22:21:33.295Z,1753309293.295 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-07-23T22:21:33.295Z,1753309293.295 [MassServo](INFO): Powering down 2025-07-23T22:21:33.296Z,1753309293.296 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-07-23T22:21:33.296Z,1753309293.296 [RudderServo](INFO): Powering down 2025-07-23T22:21:33.297Z,1753309293.297 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-07-23T22:21:33.297Z,1753309293.297 [ThrusterHE](INFO): Powering down 2025-07-23T22:21:33.298Z,1753309293.298 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-07-23T22:21:33.298Z,1753309293.298 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-07-23T22:21:33.299Z,1753309293.299 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-07-23T22:21:33.299Z,1753309293.299 [CBIT](DEBUG): Powering off loads. 2025-07-23T22:21:33.310Z,1753309293.310 [CBIT](DEBUG): Disabling WDT. 2025-07-23T22:21:33.322Z,1753309293.322 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-23T22:21:33.322Z,1753309293.322 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-23T22:21:33.323Z,1753309293.323 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.381Z,1753309293.381 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.384Z,1753309293.384 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.390Z,1753309293.390 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.396Z,1753309293.396 [DAT ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.438Z,1753309293.438 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.479Z,1753309293.479 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.483Z,1753309293.483 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.502Z,1753309293.502 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-07-23T22:21:33.503Z,1753309293.503 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-07-23T22:21:33.517Z,1753309293.517 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-07-23T22:21:33.581Z,1753309293.581 [logger ThreadHandler](INFO): Thread cancelled.