2024-12-16T16:01:14.065Z,1734364874.065 [Supervisor](DEBUG): Initializing supervisor. 2024-12-16T16:01:14.069Z,1734364874.069 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-12-16T16:01:14.070Z,1734364874.070 [SyncHandler](INFO): Protected caller Thread ID is 5356 2024-12-16T16:01:14.070Z,1734364874.070 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-12-16T16:01:14.071Z,1734364874.071 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-12-16T16:01:14.071Z,1734364874.071 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5357 2024-12-16T16:01:14.075Z,1734364874.075 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-12-16T16:01:14.092Z,1734364874.092 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-12-16T16:01:14.094Z,1734364874.094 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-12-16T16:01:14.094Z,1734364874.094 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5358 2024-12-16T16:01:14.098Z,1734364874.098 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-12-16T16:01:14.099Z,1734364874.099 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-12-16T16:01:14.099Z,1734364874.099 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5359 2024-12-16T16:01:14.101Z,1734364874.101 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-12-16T16:01:14.102Z,1734364874.102 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-12-16T16:01:14.103Z,1734364874.103 [logger ThreadHandler](INFO): Protected caller Thread ID is 5360 2024-12-16T16:01:14.106Z,1734364874.106 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-12-16T16:01:14.107Z,1734364874.107 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-12-16T16:01:14.108Z,1734364874.108 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-12-16T16:01:14.388Z,1734364874.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-12-16T16:01:14.389Z,1734364874.389 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-12-16T16:01:14.479Z,1734364874.479 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-12-16T16:01:15.088Z,1734364875.088 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-12-16T16:01:15.088Z,1734364875.088 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-12-16T16:01:15.453Z,1734364875.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-12-16T16:01:15.454Z,1734364875.454 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-12-16T16:01:15.671Z,1734364875.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-12-16T16:01:15.672Z,1734364875.672 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-12-16T16:01:16.090Z,1734364876.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-12-16T16:01:16.091Z,1734364876.091 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-12-16T16:01:16.315Z,1734364876.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-12-16T16:01:16.315Z,1734364876.315 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-12-16T16:01:16.849Z,1734364876.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-12-16T16:01:16.850Z,1734364876.850 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-12-16T16:01:16.996Z,1734364876.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-12-16T16:01:16.997Z,1734364876.997 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-12-16T16:01:17.087Z,1734364877.087 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-12-16T16:01:17.932Z,1734364877.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-12-16T16:01:17.932Z,1734364877.932 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-12-16T16:01:18.324Z,1734364878.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-12-16T16:01:18.324Z,1734364878.324 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-12-16T16:01:18.520Z,1734364878.520 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-12-16T16:01:18.520Z,1734364878.520 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-12-16T16:01:18.642Z,1734364878.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-12-16T16:01:18.643Z,1734364878.643 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-12-16T16:01:18.882Z,1734364878.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-12-16T16:01:18.883Z,1734364878.883 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-12-16T16:01:19.143Z,1734364879.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-12-16T16:01:19.145Z,1734364879.145 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-12-16T16:01:19.146Z,1734364879.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-12-16T16:01:19.243Z,1734364879.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-12-16T16:01:19.355Z,1734364879.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-12-16T16:01:19.449Z,1734364879.449 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-12-16T16:01:19.563Z,1734364879.563 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-12-16T16:01:19.666Z,1734364879.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-12-16T16:01:19.794Z,1734364879.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-12-16T16:01:19.958Z,1734364879.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-12-16T16:01:20.117Z,1734364880.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-12-16T16:01:20.238Z,1734364880.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-12-16T16:01:20.523Z,1734364880.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-12-16T16:01:20.524Z,1734364880.524 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-12-16T16:01:20.525Z,1734364880.525 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-12-16T16:01:20.538Z,1734364880.538 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-12-16T16:01:20.626Z,1734364880.626 [VerticalControl](DEBUG): Construct VerticalControl. 2024-12-16T16:01:20.833Z,1734364880.833 [VerticalControl] Loaded 2024-12-16T16:01:20.833Z,1734364880.833 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-12-16T16:01:20.854Z,1734364880.854 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-12-16T16:01:20.901Z,1734364880.901 [HorizontalControl] Loaded 2024-12-16T16:01:20.901Z,1734364880.901 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-12-16T16:01:20.903Z,1734364880.903 [SpeedControl](DEBUG): Construct SpeedControl. 2024-12-16T16:01:20.906Z,1734364880.906 [SpeedControl] Loaded 2024-12-16T16:01:20.906Z,1734364880.906 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-12-16T16:01:20.909Z,1734364880.909 [LoopControl](DEBUG): Construct LoopControl. 2024-12-16T16:01:20.909Z,1734364880.909 [LoopControl] Loaded 2024-12-16T16:01:20.910Z,1734364880.910 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-12-16T16:01:20.910Z,1734364880.910 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-12-16T16:01:20.911Z,1734364880.911 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-12-16T16:01:20.920Z,1734364880.920 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-12-16T16:01:20.921Z,1734364880.921 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-12-16T16:01:21.263Z,1734364881.263 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-12-16T16:01:21.263Z,1734364881.263 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-12-16T16:01:21.393Z,1734364881.393 [BuoyancyServo] Loaded 2024-12-16T16:01:21.393Z,1734364881.393 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-12-16T16:01:21.415Z,1734364881.415 [ElevatorServo] Loaded 2024-12-16T16:01:21.415Z,1734364881.415 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-12-16T16:01:21.436Z,1734364881.436 [MassServo] Loaded 2024-12-16T16:01:21.436Z,1734364881.436 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-12-16T16:01:21.456Z,1734364881.456 [RudderServo] Loaded 2024-12-16T16:01:21.456Z,1734364881.456 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-12-16T16:01:21.473Z,1734364881.473 [ThrusterHE] Loaded 2024-12-16T16:01:21.473Z,1734364881.473 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-12-16T16:01:21.473Z,1734364881.473 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-12-16T16:01:21.474Z,1734364881.474 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-12-16T16:01:21.585Z,1734364881.585 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-12-16T16:01:21.585Z,1734364881.585 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-12-16T16:01:21.598Z,1734364881.598 [NavChart] Loaded 2024-12-16T16:01:21.599Z,1734364881.599 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-12-16T16:01:21.604Z,1734364881.604 [UniversalFixResidualReporter] Loaded 2024-12-16T16:01:21.604Z,1734364881.604 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-12-16T16:01:21.605Z,1734364881.605 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-12-16T16:01:21.605Z,1734364881.605 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-12-16T16:01:21.661Z,1734364881.661 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-12-16T16:01:21.662Z,1734364881.662 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-12-16T16:01:22.111Z,1734364882.111 [AHRS_M2] Loaded 2024-12-16T16:01:22.111Z,1734364882.111 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-12-16T16:01:22.942Z,1734364882.942 [BPC1] Loaded 2024-12-16T16:01:22.942Z,1734364882.942 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-12-16T16:01:23.016Z,1734364883.016 [DataOverHttps] Loaded 2024-12-16T16:01:23.016Z,1734364883.016 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-12-16T16:01:23.017Z,1734364883.017 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409454E0 2024-12-16T16:01:23.018Z,1734364883.018 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5440 2024-12-16T16:01:23.041Z,1734364883.041 [Depth_Keller] Loaded 2024-12-16T16:01:23.041Z,1734364883.041 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-12-16T16:01:23.051Z,1734364883.051 [DropWeight] Loaded 2024-12-16T16:01:23.052Z,1734364883.052 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-12-16T16:01:23.116Z,1734364883.116 [NAL9602] Loaded 2024-12-16T16:01:23.116Z,1734364883.116 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-12-16T16:01:23.145Z,1734364883.145 [Onboard] Loaded 2024-12-16T16:01:23.146Z,1734364883.146 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-12-16T16:01:23.147Z,1734364883.147 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2024-12-16T16:01:23.147Z,1734364883.147 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5441 2024-12-16T16:01:23.154Z,1734364883.154 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-12-16T16:01:23.168Z,1734364883.168 [PowerOnly] Loaded 2024-12-16T16:01:23.168Z,1734364883.168 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-12-16T16:01:23.181Z,1734364883.181 [Power24vConverter] Loaded 2024-12-16T16:01:23.182Z,1734364883.182 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-12-16T16:01:23.197Z,1734364883.197 [Radio_Surface] Loaded 2024-12-16T16:01:23.198Z,1734364883.198 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-12-16T16:01:23.199Z,1734364883.199 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0 2024-12-16T16:01:23.199Z,1734364883.199 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5442 2024-12-16T16:01:23.200Z,1734364883.200 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-12-16T16:01:23.201Z,1734364883.201 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-12-16T16:01:23.251Z,1734364883.251 [DepthRateCalculator] Loaded 2024-12-16T16:01:23.251Z,1734364883.251 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-12-16T16:01:23.256Z,1734364883.256 [PitchRateCalculator] Loaded 2024-12-16T16:01:23.256Z,1734364883.256 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-12-16T16:01:23.265Z,1734364883.265 [SpeedCalculator] Loaded 2024-12-16T16:01:23.265Z,1734364883.265 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-12-16T16:01:23.269Z,1734364883.269 [YawRateCalculator] Loaded 2024-12-16T16:01:23.270Z,1734364883.270 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-12-16T16:01:23.288Z,1734364883.288 [ElevatorOffsetCalculator] Loaded 2024-12-16T16:01:23.288Z,1734364883.288 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-12-16T16:01:23.289Z,1734364883.289 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-12-16T16:01:23.290Z,1734364883.290 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-12-16T16:01:23.422Z,1734364883.422 [CANONSampler] Loaded 2024-12-16T16:01:23.423Z,1734364883.423 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-12-16T16:01:23.485Z,1734364883.485 [CTD_Seabird] Loaded 2024-12-16T16:01:23.485Z,1734364883.485 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-12-16T16:01:23.487Z,1734364883.487 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A614E0 2024-12-16T16:01:23.488Z,1734364883.488 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5443 2024-12-16T16:01:23.510Z,1734364883.510 [PAR_Licor] Loaded 2024-12-16T16:01:23.510Z,1734364883.510 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-12-16T16:01:23.555Z,1734364883.555 [WetLabsBB2FL] Loaded 2024-12-16T16:01:23.555Z,1734364883.555 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-12-16T16:01:23.556Z,1734364883.556 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A914E0 2024-12-16T16:01:23.556Z,1734364883.556 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5444 2024-12-16T16:01:23.557Z,1734364883.557 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-12-16T16:01:23.558Z,1734364883.558 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-12-16T16:01:23.583Z,1734364883.583 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-12-16T16:01:23.584Z,1734364883.584 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-12-16T16:01:23.919Z,1734364883.919 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-12-16T16:01:23.919Z,1734364883.919 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-12-16T16:01:24.057Z,1734364884.057 [SBIT](DEBUG): Construct Startup Built In Test. 2024-12-16T16:01:24.066Z,1734364884.066 [SBIT] Loaded 2024-12-16T16:01:24.066Z,1734364884.066 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-12-16T16:01:24.069Z,1734364884.069 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-12-16T16:01:24.081Z,1734364884.081 [IBIT] Loaded 2024-12-16T16:01:24.081Z,1734364884.081 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-12-16T16:01:24.087Z,1734364884.087 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-12-16T16:01:24.190Z,1734364884.190 [CBIT] Loaded 2024-12-16T16:01:24.190Z,1734364884.190 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-12-16T16:01:24.190Z,1734364884.190 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-12-16T16:01:24.191Z,1734364884.191 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-12-16T16:01:24.322Z,1734364884.322 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-12-16T16:01:24.329Z,1734364884.329 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-12-16T16:01:24.331Z,1734364884.331 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-12-16T16:01:24.343Z,1734364884.343 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-12-16T16:01:24.344Z,1734364884.344 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF04E0 2024-12-16T16:01:24.344Z,1734364884.344 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5445 2024-12-16T16:01:24.348Z,1734364884.348 [Supervisor](INFO): Main Thread ID is 5355 2024-12-16T16:01:24.349Z,1734364884.349 [Supervisor](DEBUG): Running supervisor. 2024-12-16T16:01:24.349Z,1734364884.349 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5446 2024-12-16T16:01:24.350Z,1734364884.350 [CommandExec](INFO): Initializing the command executive. 2024-12-16T16:01:24.351Z,1734364884.351 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5447 2024-12-16T16:01:24.354Z,1734364884.354 [controlThread ThreadHandler](INFO): Handler Thread ID is 5448 2024-12-16T16:01:24.354Z,1734364884.354 [controlThread](DEBUG): Initializing ControlThread 2024-12-16T16:01:24.355Z,1734364884.355 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-12-16T16:01:24.356Z,1734364884.356 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-12-16T16:01:24.357Z,1734364884.357 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-12-16T16:01:24.358Z,1734364884.358 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-12-16T16:01:24.359Z,1734364884.359 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-12-16T16:01:24.360Z,1734364884.360 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-12-16T16:01:24.364Z,1734364884.364 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-12-16T16:01:24.364Z,1734364884.364 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-12-16T16:01:24.364Z,1734364884.364 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-12-16T16:01:24.364Z,1734364884.365 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-12-16T16:01:24.365Z,1734364884.365 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-12-16T16:01:24.370Z,1734364884.370 [SBIT](INFO): Initialize SBIT Component. 2024-12-16T16:01:24.370Z,1734364884.370 [SBIT](IMPORTANT): git: 2024-12-05_A 2024-12-16T16:01:24.370Z,1734364884.370 [SBIT](INFO): git hash: a7f03fd092d8b667f0fd65952c0a44b5022c471d 2024-12-16T16:01:24.371Z,1734364884.371 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-12-16T16:01:24.372Z,1734364884.372 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-12-16T16:01:24.373Z,1734364884.373 [SBIT](INFO): Beginning SBIT in 60.000000 seconds. 2024-12-16T16:01:24.373Z,1734364884.373 [IBIT](INFO): Initialize IBIT Component. 2024-12-16T16:01:24.374Z,1734364884.374 [CBIT](DEBUG): Initialize CBIT Component. 2024-12-16T16:01:24.375Z,1734364884.375 [logger ThreadHandler](INFO): Handler Thread ID is 5449 2024-12-16T16:01:24.386Z,1734364884.386 [CBIT](DEBUG): Initialized mux pins. 2024-12-16T16:01:24.386Z,1734364884.386 [CBIT](DEBUG): Initializing the watchdog timer. 2024-12-16T16:01:24.394Z,1734364884.394 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5450 2024-12-16T16:01:24.396Z,1734364884.396 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-12-16T16:01:24.406Z,1734364884.406 [Onboard ThreadHandler](INFO): Handler Thread ID is 5451 2024-12-16T16:01:24.410Z,1734364884.410 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-12-16T16:01:24.411Z,1734364884.411 [CBIT](DEBUG): Initializing heartbeat. 2024-12-16T16:01:24.427Z,1734364884.427 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5452 2024-12-16T16:01:24.442Z,1734364884.442 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5453 2024-12-16T16:01:24.443Z,1734364884.443 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-12-16T16:01:24.447Z,1734364884.447 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5455 2024-12-16T16:01:24.450Z,1734364884.450 [WetLabsBB2FL](INFO): Powering up 2024-12-16T16:01:24.451Z,1734364884.451 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5457 2024-12-16T16:01:24.455Z,1734364884.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-12-16T16:01:24.455Z,1734364884.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-12-16T16:01:24.455Z,1734364884.455 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-12-16T16:01:24.455Z,1734364884.455 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-12-16T16:01:24.456Z,1734364884.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-12-16T16:01:24.456Z,1734364884.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-12-16T16:01:24.456Z,1734364884.456 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-12-16T16:01:24.456Z,1734364884.456 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-12-16T16:01:24.482Z,1734364884.482 [CBIT](DEBUG): Deactivating GF circuits. 2024-12-16T16:01:24.482Z,1734364884.482 [CBIT](DEBUG): Deactivating emergency mode. 2024-12-16T16:01:24.522Z,1734364884.522 [CBIT](DEBUG): Backplane powered. 2024-12-16T16:01:24.522Z,1734364884.522 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-12-16T16:01:24.523Z,1734364884.523 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-12-16T16:01:24.523Z,1734364884.523 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-12-16T16:01:24.524Z,1734364884.524 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-12-16T16:01:24.573Z,1734364884.573 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-12-16T16:01:24.594Z,1734364884.594 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-12-16T16:01:24.642Z,1734364884.642 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-12-16T16:01:24.642Z,1734364884.642 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-12-16T16:01:24.643Z,1734364884.643 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-12-16T16:01:24.644Z,1734364884.644 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-12-16T16:01:24.830Z,1734364884.830 [Radio_Surface](INFO): Powering up 2024-12-16T16:01:24.971Z,1734364884.971 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-12-16T16:01:24.985Z,1734364884.985 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-12-16T16:01:24.994Z,1734364884.994 [Default:A.Wait](DEBUG): Construct Wait. 2024-12-16T16:01:24.996Z,1734364884.996 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-12-16T16:01:25.041Z,1734364885.041 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-12-16T16:01:25.044Z,1734364885.044 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-12-16T16:01:25.056Z,1734364885.056 [Default:E.Execute](DEBUG): Construct Execute. 2024-12-16T16:01:25.069Z,1734364885.069 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-12-16T16:01:25.073Z,1734364885.073 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2024-12-16T16:01:25.096Z,1734364885.096 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-12-16T16:01:25.155Z,1734364885.155 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m 2024-12-16T16:01:25.157Z,1734364885.157 [PowerOnly](INFO): Powering up loadControl 2024-12-16T16:01:25.174Z,1734364885.174 [Power24vConverter](INFO): Powering up. 2024-12-16T16:01:25.175Z,1734364885.175 [CANONSampler](INFO): Powering down 2024-12-16T16:01:25.214Z,1734364885.214 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-12-16T16:01:25.236Z,1734364885.236 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-12-16T16:01:25.242Z,1734364885.242 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-12-16T16:01:25.243Z,1734364885.243 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-12-16T16:01:25.250Z,1734364885.250 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-12-16T16:01:25.251Z,1734364885.251 [MassServo](DEBUG): Initializing EZServoServo. 2024-12-16T16:01:25.258Z,1734364885.258 [MassServo](DEBUG): Initializing MassServo. 2024-12-16T16:01:25.259Z,1734364885.259 [RudderServo](DEBUG): Initializing EZServoServo. 2024-12-16T16:01:25.266Z,1734364885.266 [RudderServo](DEBUG): Initializing RudderServo. 2024-12-16T16:01:25.267Z,1734364885.267 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-12-16T16:01:25.274Z,1734364885.274 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-12-16T16:01:27.062Z,1734364887.062 [WetLabsBB2FL](INFO): Powering down 2024-12-16T16:01:30.978Z,1734364890.978 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-12-16T16:01:31.474Z,1734364891.474 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-12-16T16:01:37.085Z,1734364897.085 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005194 2024-12-16T16:01:52.035Z,1734364912.035 [CommandExec](IMPORTANT): got command strobe off 2024-12-16T16:01:52.035Z,1734364912.035 [CommandExec](IMPORTANT): Deactivating strobe 2024-12-16T16:01:53.363Z,1734364913.363 [NAL9602](INFO): Powering up NAL9602 2024-12-16T16:02:04.271Z,1734364924.271 [NAL9602](INFO): NAL9602 initialized 2024-12-16T16:02:07.267Z,1734364927.267 [CommandExec](IMPORTANT): got command strobe off 2024-12-16T16:02:07.267Z,1734364927.267 [CommandExec](IMPORTANT): Deactivating strobe 2024-12-16T16:02:24.930Z,1734364944.930 [SBIT](IMPORTANT): Beginning Startup BIT 2024-12-16T16:02:24.938Z,1734364944.938 [CBIT](IMPORTANT): Beginning ground fault scan 2024-12-16T16:02:32.551Z,1734364952.551 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-16T16:02:35.609Z,1734364955.609 [CommandExec](IMPORTANT): got command get 2024-12-16T16:02:35.609Z,1734364955.609 [CommandExec](FAULT): Incomplete syntax. Try: help get 2024-12-16T16:02:35.829Z,1734364955.829 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-12-16T16:02:36.212Z,1734364956.212 [CBIT](IMPORTANT): Beginning ground fault scan 2024-12-16T16:02:47.723Z,1734364967.723 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-12-16T16:02:48.140Z,1734364968.140 [CBIT](IMPORTANT): Beginning ground fault scan 2024-12-16T16:02:53.727Z,1734364973.727 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-16T16:02:57.376Z,1734364977.376 [CommandExec](IMPORTANT): got command show variable sonardyne_nano 2024-12-16T16:02:57.547Z,1734364977.547 [CommandExec](IMPORTANT): Sonardyne_Nano.loadControl (none) 2024-12-16T16:02:57.548Z,1734364977.548 [CommandExec](IMPORTANT): Sonardyne_Nano.uart (none) 2024-12-16T16:02:57.548Z,1734364977.548 [CommandExec](IMPORTANT): Sonardyne_Nano.baud (bit_per_second) 2024-12-16T16:02:57.568Z,1734364977.568 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup (bool) 2024-12-16T16:02:57.568Z,1734364977.568 [CommandExec](IMPORTANT): Sonardyne_Nano.simulateHardware (bool) 2024-12-16T16:02:57.569Z,1734364977.569 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMin (percent) 2024-12-16T16:02:57.569Z,1734364977.569 [CommandExec](IMPORTANT): Sonardyne_Nano.chargeMax (percent) 2024-12-16T16:02:59.052Z,1734364979.052 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.015451 CHAN A1 (24V): 0.000786 CHAN A2 (12V): -0.002064 CHAN A3 (5V): -0.001671 CHAN B0 (3.3V): -0.000133 CHAN B1 (3.15aV): -0.000223 CHAN B2 (3.15bV): -0.000449 CHAN B3 (GND): 0.000183 OPEN: -0.000510 Full Scale: +/- 1 mA 2024-12-16T16:03:12.320Z,1734364992.320 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-16T16:03:18.808Z,1734364998.808 [SBIT](IMPORTANT): SBIT PASSED 2024-12-16T16:03:18.808Z,1734364998.808 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-12-16T16:03:18.809Z,1734364998.809 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count; 2024-12-16T16:03:18.809Z,1734364998.809 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=321.456843 cubic_centimeter; 2024-12-16T16:03:18.810Z,1734364998.810 [SBIT](IMPORTANT): VerticalControl.elevLimit=9 degree; 2024-12-16T16:03:18.811Z,1734364998.811 [SBIT](IMPORTANT): VerticalControl.massDefault=10.109123 millimeter; 2024-12-16T16:03:19.204Z,1734364999.204 [MissionManager](IMPORTANT): Started mission Startup 2024-12-16T16:03:19.204Z,1734364999.204 [Startup] Running Loop=1 2024-12-16T16:03:19.204Z,1734364999.204 [Startup](DEBUG): Aggregate::initialize Startup 2024-12-16T16:03:19.204Z,1734364999.204 [Startup:A.GoToSurface] Running Loop=1 2024-12-16T16:03:19.204Z,1734364999.204 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-12-16T16:03:19.205Z,1734364999.205 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-12-16T16:03:19.205Z,1734364999.205 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-12-16T16:03:19.206Z,1734364999.206 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-12-16T16:03:19.206Z,1734364999.206 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-12-16T16:03:19.206Z,1734364999.206 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-12-16T16:03:19.207Z,1734364999.207 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-12-16T16:03:19.209Z,1734364999.209 [Startup:StartupSatComms] Running Loop=1 2024-12-16T16:03:19.209Z,1734364999.209 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-12-16T16:03:19.209Z,1734364999.209 [Startup:StartupSatComms:A] Running Loop=1 2024-12-16T16:03:19.612Z,1734364999.612 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-12-16T16:03:26.756Z,1734365006.756 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.loadAtStartup 2024-12-16T16:03:26.757Z,1734365006.757 [CommandExec](IMPORTANT): Sonardyne_Nano.loadAtStartup 0 bool 2024-12-16T16:03:32.139Z,1734365012.139 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:04:09.742Z,1734365049.742 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-16T16:04:19.428Z,1734365059.428 [Startup:StartupSatComms:A](INFO): Timed out from 2024-12-16T16:03:19.2Z 2024-12-16T16:04:19.428Z,1734365059.428 [Startup:StartupSatComms:A] Stopped 2024-12-16T16:04:19.428Z,1734365059.428 [Startup:StartupSatComms:B] Running Loop=1 2024-12-16T16:04:19.801Z,1734365059.801 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-12-16T16:04:24.681Z,1734365064.681 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-12-16T16:04:24.681Z,1734365064.681 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-12-16T16:04:24.704Z,1734365064.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-12-16T16:04:25.104Z,1734365065.104 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-12-16T16:04:25.104Z,1734365065.104 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-12-16T16:04:26.267Z,1734365066.267 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241213T000542/Courier0004.lzma 2024-12-16T16:04:27.267Z,1734365067.267 [DataOverHttps](INFO): Moved sent file to Logs/20241213T000542/Courier0004.lzma.bak 2024-12-16T16:04:27.268Z,1734365067.268 [DataOverHttps](INFO): SBD MOMSN=23698971 2024-12-16T16:04:42.781Z,1734365082.781 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241216T160114/Courier0000.lzma 2024-12-16T16:04:43.784Z,1734365083.784 [DataOverHttps](INFO): Moved sent file to Logs/20241216T160114/Courier0000.lzma.bak 2024-12-16T16:04:43.784Z,1734365083.784 [DataOverHttps](INFO): SBD MOMSN=23698973 2024-12-16T16:04:51.372Z,1734365091.372 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-16T16:04:59.951Z,1734365099.951 [DataOverHttps](INFO): Sending 354 bytes from file Logs/20241213T000542/Express0005.lzma 2024-12-16T16:05:00.951Z,1734365100.951 [DataOverHttps](INFO): Moved sent file to Logs/20241213T000542/Express0005.lzma.bak 2024-12-16T16:05:00.952Z,1734365100.952 [DataOverHttps](INFO): SBD MOMSN=23698976 2024-12-16T16:05:13.137Z,1734365113.137 [BPC1](ERROR): BPC1A: No match for serial number FFFF in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-16T16:05:16.520Z,1734365116.520 [DataOverHttps](INFO): Sending 1191 bytes from file Logs/20241216T160114/Express0001.lzma 2024-12-16T16:05:17.519Z,1734365117.519 [DataOverHttps](INFO): Moved sent file to Logs/20241216T160114/Express0001.lzma.bak 2024-12-16T16:05:17.520Z,1734365117.520 [DataOverHttps](INFO): SBD MOMSN=23698990 2024-12-16T16:05:17.629Z,1734365117.629 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:05:18.819Z,1734365118.819 [Startup:StartupSatComms:B] Stopped 2024-12-16T16:05:18.819Z,1734365118.819 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-12-16T16:05:18.819Z,1734365118.819 [Startup:StartupSatComms] Stopped 2024-12-16T16:05:18.819Z,1734365118.819 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-12-16T16:05:18.820Z,1734365118.820 [Startup](INFO): Completed Startup 2024-12-16T16:05:18.820Z,1734365118.820 [MissionManager](INFO): Startup is completed. 2024-12-16T16:05:18.820Z,1734365118.820 [MissionManager](INFO): Uninitializing Mission Startup 2024-12-16T16:05:18.820Z,1734365118.820 [Startup] Stopped 2024-12-16T16:05:18.821Z,1734365118.821 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-12-16T16:05:18.821Z,1734365118.821 [Startup:A.GoToSurface] Stopped 2024-12-16T16:05:18.821Z,1734365118.821 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-12-16T16:05:19.212Z,1734365119.212 [MissionManager](IMPORTANT): Started mission Default 2024-12-16T16:05:19.212Z,1734365119.212 [Default] Running Loop=1 2024-12-16T16:05:19.212Z,1734365119.212 [Default](DEBUG): Aggregate::initialize Default 2024-12-16T16:05:19.212Z,1734365119.212 [Default:B.GoToSurface] Running Loop=1 2024-12-16T16:05:19.212Z,1734365119.212 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-12-16T16:05:19.213Z,1734365119.213 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-12-16T16:05:19.213Z,1734365119.213 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-12-16T16:05:19.213Z,1734365119.213 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-12-16T16:05:19.214Z,1734365119.214 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-12-16T16:05:19.214Z,1734365119.214 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-12-16T16:05:19.214Z,1734365119.214 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-12-16T16:05:19.215Z,1734365119.215 [Default:A.Wait] Running Loop=1 2024-12-16T16:05:19.215Z,1734365119.215 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-12-16T16:05:32.572Z,1734365132.572 [Default:A.Wait](INFO): Done Waiting. 2024-12-16T16:05:32.573Z,1734365132.573 [Default:A.Wait] Stopped 2024-12-16T16:05:32.573Z,1734365132.573 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-12-16T16:05:32.966Z,1734365132.966 [Default:CheckIn] Running Loop=1 2024-12-16T16:05:32.966Z,1734365132.966 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-12-16T16:05:32.966Z,1734365132.966 [Default:CheckIn:Read_GPS] Running Loop=1 2024-12-16T16:05:33.348Z,1734365133.348 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-12-16T16:05:41.413Z,1734365141.413 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-12-16T16:07:08.279Z,1734365228.279 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-12-16T16:07:25.694Z,1734365245.694 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-12-16T16:07:25.694Z,1734365245.694 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-12-16T16:07:25.709Z,1734365245.709 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-12-16T16:07:26.092Z,1734365246.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-12-16T16:07:26.092Z,1734365246.092 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-12-16T16:07:31.298Z,1734365251.298 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-12-16T16:07:31.302Z,1734365251.302 [BPC1](ERROR): BPC1A failed to parse battery stick message. 2024-12-16T16:08:20.637Z,1734365300.637 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:08:27.526Z,1734365307.526 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-16T16:09:03.875Z,1734365343.875 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:09:31.340Z,1734365371.340 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:10:12.988Z,1734365412.988 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-16T16:10:17.006Z,1734365417.006 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:10:26.662Z,1734365426.662 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-12-16T16:10:26.662Z,1734365426.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-12-16T16:10:26.677Z,1734365426.677 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-12-16T16:10:27.069Z,1734365427.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-12-16T16:10:27.069Z,1734365427.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-12-16T16:10:31.544Z,1734365431.544 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-12-16T16:10:33.124Z,1734365433.124 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-16T16:05:32.0Z 2024-12-16T16:10:33.124Z,1734365433.124 [Default:CheckIn:Read_GPS] Stopped 2024-12-16T16:10:33.124Z,1734365433.124 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-12-16T16:10:33.520Z,1734365433.520 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-12-16T16:10:39.921Z,1734365439.921 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20241216T160114/Courier0004.lzma 2024-12-16T16:10:40.924Z,1734365440.924 [DataOverHttps](INFO): Moved sent file to Logs/20241216T160114/Courier0004.lzma.bak 2024-12-16T16:10:40.924Z,1734365440.924 [DataOverHttps](INFO): SBD MOMSN=23699070 2024-12-16T16:10:42.455Z,1734365442.455 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-12-16T16:10:51.278Z,1734365451.278 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-12-16T16:10:56.362Z,1734365456.362 [DataOverHttps](INFO): Sending 196 bytes from file Logs/20241216T160114/Express0005.lzma 2024-12-16T16:10:57.363Z,1734365457.363 [DataOverHttps](INFO): Moved sent file to Logs/20241216T160114/Express0005.lzma.bak 2024-12-16T16:10:57.363Z,1734365457.363 [DataOverHttps](INFO): SBD MOMSN=23699072 2024-12-16T16:10:58.584Z,