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.