2023-06-21T16:51:34.506Z,1687366294.506 [Supervisor](DEBUG): Initializing supervisor. 2023-06-21T16:51:34.511Z,1687366294.511 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-06-21T16:51:34.511Z,1687366294.511 [SyncHandler](INFO): Protected caller Thread ID is 831 2023-06-21T16:51:34.512Z,1687366294.512 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-06-21T16:51:34.513Z,1687366294.513 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-06-21T16:51:34.513Z,1687366294.513 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832 2023-06-21T16:51:34.517Z,1687366294.517 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-06-21T16:51:34.536Z,1687366294.536 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-06-21T16:51:34.537Z,1687366294.537 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-06-21T16:51:34.537Z,1687366294.537 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833 2023-06-21T16:51:34.541Z,1687366294.541 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-06-21T16:51:34.542Z,1687366294.542 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-06-21T16:51:34.543Z,1687366294.543 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834 2023-06-21T16:51:34.545Z,1687366294.545 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-06-21T16:51:34.546Z,1687366294.546 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-06-21T16:51:34.546Z,1687366294.546 [logger ThreadHandler](INFO): Protected caller Thread ID is 835 2023-06-21T16:51:34.550Z,1687366294.550 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-06-21T16:51:34.550Z,1687366294.550 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-06-21T16:51:34.556Z,1687366294.556 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-06-21T16:51:34.652Z,1687366294.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-06-21T16:51:34.654Z,1687366294.654 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-06-21T16:51:34.865Z,1687366294.865 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-06-21T16:51:34.867Z,1687366294.867 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-06-21T16:51:35.116Z,1687366295.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-06-21T16:51:35.118Z,1687366295.118 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-06-21T16:51:35.225Z,1687366295.225 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-06-21T16:51:35.227Z,1687366295.227 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-06-21T16:51:35.921Z,1687366295.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-06-21T16:51:35.922Z,1687366295.922 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-06-21T16:51:36.389Z,1687366296.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-06-21T16:51:36.390Z,1687366296.390 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-06-21T16:51:36.861Z,1687366296.861 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-06-21T16:51:36.862Z,1687366296.862 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-06-21T16:51:37.266Z,1687366297.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-06-21T16:51:37.267Z,1687366297.267 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-06-21T16:51:37.594Z,1687366297.594 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-06-21T16:51:37.596Z,1687366297.596 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-06-21T16:51:38.433Z,1687366298.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-06-21T16:51:38.434Z,1687366298.434 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-06-21T16:51:38.649Z,1687366298.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-06-21T16:51:38.650Z,1687366298.650 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-06-21T16:51:38.791Z,1687366298.791 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-06-21T16:51:38.793Z,1687366298.793 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-06-21T16:51:38.874Z,1687366298.874 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-06-21T16:51:38.953Z,1687366298.953 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-06-21T16:51:39.132Z,1687366299.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-06-21T16:51:39.133Z,1687366299.133 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-06-21T16:51:39.348Z,1687366299.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-06-21T16:51:39.350Z,1687366299.350 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2023-06-21T16:51:39.353Z,1687366299.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2023-06-21T16:51:39.438Z,1687366299.438 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2023-06-21T16:51:39.696Z,1687366299.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-06-21T16:51:39.697Z,1687366299.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2023-06-21T16:51:39.794Z,1687366299.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2023-06-21T16:51:39.950Z,1687366299.950 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2023-06-21T16:51:40.123Z,1687366300.123 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2023-06-21T16:51:40.208Z,1687366300.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2023-06-21T16:51:40.316Z,1687366300.316 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2023-06-21T16:51:40.419Z,1687366300.419 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2023-06-21T16:51:40.571Z,1687366300.571 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2023-06-21T16:51:40.666Z,1687366300.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2023-06-21T16:51:40.763Z,1687366300.763 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-06-21T16:51:40.796Z,1687366300.796 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-06-21T16:51:40.979Z,1687366300.979 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-06-21T16:51:40.980Z,1687366300.980 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-06-21T16:51:41.210Z,1687366301.210 [BuoyancyServo] Loaded 2023-06-21T16:51:41.211Z,1687366301.211 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-06-21T16:51:41.231Z,1687366301.231 [ElevatorServo] Loaded 2023-06-21T16:51:41.232Z,1687366301.232 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-06-21T16:51:41.252Z,1687366301.252 [MassServo] Loaded 2023-06-21T16:51:41.252Z,1687366301.252 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-06-21T16:51:41.272Z,1687366301.272 [RudderServo] Loaded 2023-06-21T16:51:41.272Z,1687366301.272 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-06-21T16:51:41.287Z,1687366301.287 [ThrusterHE] Loaded 2023-06-21T16:51:41.288Z,1687366301.288 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-06-21T16:51:41.288Z,1687366301.288 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-06-21T16:51:41.290Z,1687366301.290 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-06-21T16:51:41.462Z,1687366301.462 [SBIT](DEBUG): Construct Startup Built In Test. 2023-06-21T16:51:41.477Z,1687366301.477 [SBIT] Loaded 2023-06-21T16:51:41.477Z,1687366301.477 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-06-21T16:51:41.480Z,1687366301.480 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-06-21T16:51:41.501Z,1687366301.501 [IBIT] Loaded 2023-06-21T16:51:41.501Z,1687366301.501 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-06-21T16:51:41.507Z,1687366301.507 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-06-21T16:51:41.724Z,1687366301.724 [CBIT] Loaded 2023-06-21T16:51:41.724Z,1687366301.724 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-06-21T16:51:41.725Z,1687366301.725 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-06-21T16:51:41.725Z,1687366301.725 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-06-21T16:51:41.941Z,1687366301.941 [CTD_Seabird] Loaded 2023-06-21T16:51:41.941Z,1687366301.941 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-06-21T16:51:41.942Z,1687366301.942 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406644E0 2023-06-21T16:51:41.943Z,1687366301.943 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 918 2023-06-21T16:51:41.975Z,1687366301.975 [ESPComponent] Loaded 2023-06-21T16:51:41.975Z,1687366301.975 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2023-06-21T16:51:41.995Z,1687366301.995 [PAR_Licor] Loaded 2023-06-21T16:51:41.996Z,1687366301.996 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-06-21T16:51:42.026Z,1687366302.026 [WetLabsBB2FL] Loaded 2023-06-21T16:51:42.026Z,1687366302.026 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-06-21T16:51:42.027Z,1687366302.027 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406944E0 2023-06-21T16:51:42.028Z,1687366302.028 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 919 2023-06-21T16:51:42.028Z,1687366302.028 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-06-21T16:51:42.030Z,1687366302.030 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-06-21T16:51:42.099Z,1687366302.099 [DepthRateCalculator] Loaded 2023-06-21T16:51:42.099Z,1687366302.099 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-06-21T16:51:42.105Z,1687366302.105 [PitchRateCalculator] Loaded 2023-06-21T16:51:42.105Z,1687366302.105 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-06-21T16:51:42.115Z,1687366302.115 [SpeedCalculator] Loaded 2023-06-21T16:51:42.115Z,1687366302.115 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-06-21T16:51:42.120Z,1687366302.120 [YawRateCalculator] Loaded 2023-06-21T16:51:42.120Z,1687366302.120 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-06-21T16:51:42.139Z,1687366302.139 [ElevatorOffsetCalculator] Loaded 2023-06-21T16:51:42.140Z,1687366302.140 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-06-21T16:51:42.140Z,1687366302.140 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-06-21T16:51:42.141Z,1687366302.141 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-06-21T16:51:42.266Z,1687366302.266 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-06-21T16:51:42.268Z,1687366302.268 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-06-21T16:51:42.311Z,1687366302.311 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-06-21T16:51:42.313Z,1687366302.313 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-06-21T16:51:42.449Z,1687366302.449 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-06-21T16:51:42.449Z,1687366302.449 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-06-21T16:51:42.463Z,1687366302.463 [NavChart] Loaded 2023-06-21T16:51:42.463Z,1687366302.463 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-06-21T16:51:42.469Z,1687366302.469 [UniversalFixResidualReporter] Loaded 2023-06-21T16:51:42.469Z,1687366302.469 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-06-21T16:51:42.469Z,1687366302.469 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-06-21T16:51:42.470Z,1687366302.470 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-06-21T16:51:43.390Z,1687366303.390 [AHRS_M2] Loaded 2023-06-21T16:51:43.390Z,1687366303.390 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-06-21T16:51:43.782Z,1687366303.782 [BackseatComponent] Loaded 2023-06-21T16:51:43.782Z,1687366303.782 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-06-21T16:51:43.783Z,1687366303.783 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A374E0 2023-06-21T16:51:43.783Z,1687366303.783 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2023-06-21T16:51:43.786Z,1687366303.786 [LcmUniversalReporter] Loaded 2023-06-21T16:51:43.786Z,1687366303.786 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-06-21T16:51:44.526Z,1687366304.526 [BPC1] Loaded 2023-06-21T16:51:44.526Z,1687366304.526 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-06-21T16:51:44.616Z,1687366304.616 [DAT] Loaded 2023-06-21T16:51:44.616Z,1687366304.616 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2023-06-21T16:51:44.617Z,1687366304.617 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A674E0 2023-06-21T16:51:44.617Z,1687366304.617 [DAT ThreadHandler](INFO): Protected caller Thread ID is 921 2023-06-21T16:51:44.759Z,1687366304.759 [DataOverHttps] Loaded 2023-06-21T16:51:44.759Z,1687366304.759 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-06-21T16:51:44.760Z,1687366304.760 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A974E0 2023-06-21T16:51:44.761Z,1687366304.761 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 922 2023-06-21T16:51:44.782Z,1687366304.782 [Depth_Keller] Loaded 2023-06-21T16:51:44.782Z,1687366304.782 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-06-21T16:51:44.787Z,1687366304.787 [DropWeight] Loaded 2023-06-21T16:51:44.787Z,1687366304.787 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-06-21T16:51:44.848Z,1687366304.848 [NAL9602] Loaded 2023-06-21T16:51:44.848Z,1687366304.848 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-06-21T16:51:44.874Z,1687366304.874 [Onboard] Loaded 2023-06-21T16:51:44.874Z,1687366304.874 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-06-21T16:51:44.875Z,1687366304.875 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40AC74E0 2023-06-21T16:51:44.876Z,1687366304.876 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 923 2023-06-21T16:51:44.888Z,1687366304.888 [Power24vConverter] Loaded 2023-06-21T16:51:44.888Z,1687366304.888 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-06-21T16:51:44.901Z,1687366304.901 [Radio_Surface] Loaded 2023-06-21T16:51:44.902Z,1687366304.902 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-06-21T16:51:44.903Z,1687366304.903 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AF74E0 2023-06-21T16:51:44.903Z,1687366304.903 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 924 2023-06-21T16:51:44.904Z,1687366304.904 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-06-21T16:51:44.905Z,1687366304.905 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-06-21T16:51:45.003Z,1687366305.003 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-06-21T16:51:45.005Z,1687366305.005 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-06-21T16:51:45.074Z,1687366305.074 [VerticalControl](DEBUG): Construct VerticalControl. 2023-06-21T16:51:45.125Z,1687366305.125 [VerticalControl] Loaded 2023-06-21T16:51:45.125Z,1687366305.125 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-06-21T16:51:45.128Z,1687366305.128 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-06-21T16:51:45.166Z,1687366305.166 [HorizontalControl] Loaded 2023-06-21T16:51:45.166Z,1687366305.166 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-06-21T16:51:45.168Z,1687366305.168 [SpeedControl](DEBUG): Construct SpeedControl. 2023-06-21T16:51:45.170Z,1687366305.170 [SpeedControl] Loaded 2023-06-21T16:51:45.170Z,1687366305.170 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-06-21T16:51:45.173Z,1687366305.173 [LoopControl](DEBUG): Construct LoopControl. 2023-06-21T16:51:45.174Z,1687366305.174 [LoopControl] Loaded 2023-06-21T16:51:45.174Z,1687366305.174 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-06-21T16:51:45.174Z,1687366305.174 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-06-21T16:51:45.175Z,1687366305.175 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-06-21T16:51:45.191Z,1687366305.191 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-06-21T16:51:45.192Z,1687366305.192 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-06-21T16:51:45.553Z,1687366305.553 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-06-21T16:51:45.559Z,1687366305.559 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-06-21T16:51:45.562Z,1687366305.562 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-06-21T16:51:45.573Z,1687366305.573 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-06-21T16:51:45.574Z,1687366305.574 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BFD4E0 2023-06-21T16:51:45.574Z,1687366305.574 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 925 2023-06-21T16:51:45.579Z,1687366305.579 [Supervisor](INFO): Main Thread ID is 824 2023-06-21T16:51:45.579Z,1687366305.579 [Supervisor](DEBUG): Running supervisor. 2023-06-21T16:51:45.580Z,1687366305.580 [CommandExec ThreadHandler](INFO): Handler Thread ID is 926 2023-06-21T16:51:45.580Z,1687366305.580 [CommandExec](INFO): Initializing the command executive. 2023-06-21T16:51:45.582Z,1687366305.582 [CommandLine ThreadHandler](INFO): Handler Thread ID is 927 2023-06-21T16:51:45.584Z,1687366305.584 [controlThread ThreadHandler](INFO): Handler Thread ID is 928 2023-06-21T16:51:45.584Z,1687366305.584 [controlThread](DEBUG): Initializing ControlThread 2023-06-21T16:51:45.586Z,1687366305.586 [SBIT](INFO): Initialize SBIT Component. 2023-06-21T16:51:45.586Z,1687366305.586 [SBIT](IMPORTANT): git: 2023-05-17 2023-06-21T16:51:45.587Z,1687366305.587 [SBIT](INFO): git hash: e0be937c7f1ec7c51b840ed3ea323bb1c80883b5 2023-06-21T16:51:45.587Z,1687366305.587 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-06-21T16:51:45.588Z,1687366305.588 [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 2023-06-21T16:51:45.590Z,1687366305.590 [SBIT](INFO): Beginning SBIT in 109.000000 seconds. 2023-06-21T16:51:45.590Z,1687366305.590 [IBIT](INFO): Initialize IBIT Component. 2023-06-21T16:51:45.591Z,1687366305.591 [CBIT](DEBUG): Initialize CBIT Component. 2023-06-21T16:51:45.592Z,1687366305.592 [logger ThreadHandler](INFO): Handler Thread ID is 929 2023-06-21T16:51:45.605Z,1687366305.605 [CBIT](DEBUG): Initialized mux pins. 2023-06-21T16:51:45.605Z,1687366305.605 [CBIT](DEBUG): Initializing the watchdog timer. 2023-06-21T16:51:45.617Z,1687366305.617 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 930 2023-06-21T16:51:45.618Z,1687366305.618 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-06-21T16:51:45.623Z,1687366305.623 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 932 2023-06-21T16:51:45.625Z,1687366305.625 [WetLabsBB2FL](INFO): Powering up 2023-06-21T16:51:45.627Z,1687366305.627 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934 2023-06-21T16:51:45.629Z,1687366305.629 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-06-21T16:51:45.630Z,1687366305.630 [CBIT](DEBUG): Initializing heartbeat. 2023-06-21T16:51:45.643Z,1687366305.643 [DAT ThreadHandler](INFO): Handler Thread ID is 935 2023-06-21T16:51:45.644Z,1687366305.644 [DAT](INFO): Powering up 2023-06-21T16:51:45.644Z,1687366305.644 [DAT](DEBUG): Initializing DAT. 2023-06-21T16:51:45.650Z,1687366305.650 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937 2023-06-21T16:51:45.651Z,1687366305.651 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-06-21T16:51:45.662Z,1687366305.662 [Onboard ThreadHandler](INFO): Handler Thread ID is 938 2023-06-21T16:51:45.679Z,1687366305.679 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939 2023-06-21T16:51:45.697Z,1687366305.697 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940 2023-06-21T16:51:45.701Z,1687366305.701 [CBIT](DEBUG): Deactivating GF circuits. 2023-06-21T16:51:45.701Z,1687366305.701 [CBIT](DEBUG): Deactivating emergency mode. 2023-06-21T16:51:45.710Z,1687366305.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-06-21T16:51:45.710Z,1687366305.710 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-06-21T16:51:45.710Z,1687366305.710 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-06-21T16:51:45.711Z,1687366305.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA69M.000 2023-06-21T16:51:45.712Z,1687366305.712 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA69M.000 2023-06-21T16:51:45.741Z,1687366305.741 [CBIT](DEBUG): Backplane powered. 2023-06-21T16:51:45.745Z,1687366305.745 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-06-21T16:51:45.746Z,1687366305.746 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-06-21T16:51:45.746Z,1687366305.746 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-06-21T16:51:45.746Z,1687366305.746 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-06-21T16:51:45.747Z,1687366305.747 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-06-21T16:51:45.748Z,1687366305.748 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-06-21T16:51:45.748Z,1687366305.748 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-06-21T16:51:45.763Z,1687366305.763 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-06-21T16:51:45.765Z,1687366305.765 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-06-21T16:51:45.774Z,1687366305.774 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-06-21T16:51:45.774Z,1687366305.774 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-06-21T16:51:45.776Z,1687366305.776 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-06-21T16:51:45.815Z,1687366305.815 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-21T16:51:45.847Z,1687366305.847 [MissionManager](DEBUG): 2023-06-21T16:51:45.848Z,1687366305.848 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-06-21T16:51:45.928Z,1687366305.928 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-06-21T16:51:45.929Z,1687366305.929 [Default:A.Wait](DEBUG): Construct Wait. 2023-06-21T16:51:45.938Z,1687366305.938 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-21T16:51:45.965Z,1687366305.965 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-21T16:51:45.968Z,1687366305.968 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-21T16:51:45.986Z,1687366305.986 [Default:E.Execute](DEBUG): Construct Execute. 2023-06-21T16:51:46.009Z,1687366306.009 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-06-21T16:51:46.013Z,1687366306.013 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2023-06-21T16:51:46.027Z,1687366306.027 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:51:46.085Z,1687366306.085 [Radio_Surface](INFO): Powering up 2023-06-21T16:51:46.158Z,1687366306.158 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1896.044434 decibar, 0.000000 m 2023-06-21T16:51:46.159Z,1687366306.159 [Power24vConverter](INFO): Powering up. 2023-06-21T16:51:46.161Z,1687366306.161 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-06-21T16:51:46.210Z,1687366306.210 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-06-21T16:51:46.217Z,1687366306.217 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-06-21T16:51:46.218Z,1687366306.218 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-06-21T16:51:46.225Z,1687366306.225 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-06-21T16:51:46.226Z,1687366306.226 [MassServo](DEBUG): Initializing EZServoServo. 2023-06-21T16:51:46.233Z,1687366306.233 [MassServo](DEBUG): Initializing MassServo. 2023-06-21T16:51:46.234Z,1687366306.234 [RudderServo](DEBUG): Initializing EZServoServo. 2023-06-21T16:51:46.241Z,1687366306.241 [RudderServo](DEBUG): Initializing RudderServo. 2023-06-21T16:51:46.242Z,1687366306.242 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-06-21T16:51:46.249Z,1687366306.249 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-06-21T16:51:47.733Z,1687366307.733 [WetLabsBB2FL](INFO): Powering down 2023-06-21T16:51:49.581Z,1687366309.581 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:50.009Z,1687366310.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:50.537Z,1687366310.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:51.089Z,1687366311.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:51.505Z,1687366311.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:51.905Z,1687366311.905 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:52.381Z,1687366312.381 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:52.650Z,1687366312.650 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-06-21T16:51:53.037Z,1687366313.037 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:53.458Z,1687366313.458 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:53.897Z,1687366313.897 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:54.317Z,1687366314.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:54.717Z,1687366314.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:55.137Z,1687366315.137 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:55.533Z,1687366315.533 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:55.953Z,1687366315.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:56.385Z,1687366316.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:56.813Z,1687366316.813 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:57.237Z,1687366317.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:57.669Z,1687366317.669 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:58.105Z,1687366318.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:58.521Z,1687366318.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:58.957Z,1687366318.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:51:58.957Z,1687366318.957 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:51:58.957Z,1687366318.957 [AHRS_M2] Communications Fault, FailCount= 1 2023-06-21T16:51:58.957Z,1687366318.957 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:51:59.030Z,1687366319.030 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:51:59.050Z,1687366319.050 [AHRS_M2](INFO): Powering down 2023-06-21T16:51:59.905Z,1687366319.905 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-06-21T16:51:59.905Z,1687366319.905 [AHRS_M2] No Fault, FailCount= 1 2023-06-21T16:52:00.254Z,1687366320.254 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:52:01.023Z,1687366321.023 [DAT](INFO): commRate: 1200 2023-06-21T16:52:03.101Z,1687366323.101 [DAT](INFO): entering command mode 2023-06-21T16:52:03.101Z,1687366323.101 [DAT](DEBUG): checking for command mode acknowledgment 2023-06-21T16:52:03.290Z,1687366323.290 [DAT](INFO): setting verbose to 3 2023-06-21T16:52:03.543Z,1687366323.543 [DAT](INFO): set verbose to 3 2023-06-21T16:52:03.543Z,1687366323.543 [DAT](INFO): setting DatVerbose to 27440 2023-06-21T16:52:03.794Z,1687366323.794 [DAT](INFO): set DatVerbose to 27440 2023-06-21T16:52:03.795Z,1687366323.795 [DAT](INFO): setting transmit power to 8 2023-06-21T16:52:03.796Z,1687366323.796 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:04.051Z,1687366324.051 [DAT](INFO): set transmit power to 8 2023-06-21T16:52:04.051Z,1687366324.051 [DAT](INFO): setting local address to 5 2023-06-21T16:52:04.213Z,1687366324.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:04.302Z,1687366324.302 [DAT](INFO): set local address to 5 2023-06-21T16:52:04.303Z,1687366324.303 [DAT](INFO): Setting time to: 16:52:4 And date to:6/21/2023 2023-06-21T16:52:04.554Z,1687366324.554 [DAT](INFO): Local DAT time set to Wed Jun 21, 2023 16:52:04 2023-06-21T16:52:04.645Z,1687366324.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:05.077Z,1687366325.077 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:05.485Z,1687366325.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:05.897Z,1687366325.897 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:06.321Z,1687366326.321 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:06.741Z,1687366326.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:07.153Z,1687366327.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:07.561Z,1687366327.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:07.977Z,1687366327.977 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:08.401Z,1687366328.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:08.813Z,1687366328.813 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:09.225Z,1687366329.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:09.633Z,1687366329.633 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:10.057Z,1687366330.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:10.469Z,1687366330.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:10.877Z,1687366330.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:11.293Z,1687366331.293 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:11.713Z,1687366331.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:12.121Z,1687366332.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:12.537Z,1687366332.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:12.941Z,1687366332.941 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:13.357Z,1687366333.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:13.357Z,1687366333.357 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:52:13.357Z,1687366333.357 [AHRS_M2] Communications Fault, FailCount= 2 2023-06-21T16:52:13.357Z,1687366333.357 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:52:13.426Z,1687366333.426 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:52:13.454Z,1687366333.454 [AHRS_M2](INFO): Powering down 2023-06-21T16:52:14.301Z,1687366334.301 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-06-21T16:52:14.301Z,1687366334.301 [AHRS_M2] No Fault, FailCount= 2 2023-06-21T16:52:14.646Z,1687366334.646 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:52:15.055Z,1687366335.055 [NAL9602](INFO): Powering up NAL9602 2023-06-21T16:52:18.197Z,1687366338.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:18.641Z,1687366338.641 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:19.057Z,1687366339.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:19.473Z,1687366339.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:19.889Z,1687366339.889 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:20.305Z,1687366340.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:20.715Z,1687366340.715 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:21.137Z,1687366341.137 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:21.553Z,1687366341.553 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:21.973Z,1687366341.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:22.397Z,1687366342.397 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:22.817Z,1687366342.817 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:23.229Z,1687366343.229 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:23.653Z,1687366343.653 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:24.069Z,1687366344.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:24.481Z,1687366344.481 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:24.897Z,1687366344.897 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:25.309Z,1687366345.309 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:25.714Z,1687366345.714 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:26.141Z,1687366346.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:26.151Z,1687366346.151 [NAL9602](INFO): NAL9602 initialized 2023-06-21T16:52:26.557Z,1687366346.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:26.973Z,1687366346.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:27.401Z,1687366347.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:27.845Z,1687366347.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:27.845Z,1687366347.845 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:52:27.845Z,1687366347.845 [AHRS_M2] Communications Fault, FailCount= 3 2023-06-21T16:52:27.845Z,1687366347.845 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:52:27.909Z,1687366347.909 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:52:27.946Z,1687366347.946 [AHRS_M2](INFO): Powering down 2023-06-21T16:52:28.780Z,1687366348.780 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-06-21T16:52:28.780Z,1687366348.780 [AHRS_M2] No Fault, FailCount= 3 2023-06-21T16:52:29.135Z,1687366349.135 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:52:32.693Z,1687366352.693 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:33.101Z,1687366353.101 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:33.517Z,1687366353.517 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:33.941Z,1687366353.941 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:34.357Z,1687366354.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:34.789Z,1687366354.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:35.221Z,1687366355.221 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:35.649Z,1687366355.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:36.089Z,1687366356.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:36.509Z,1687366356.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:36.925Z,1687366356.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:37.349Z,1687366357.349 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:37.761Z,1687366357.761 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:38.177Z,1687366358.177 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:38.589Z,1687366358.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:39.009Z,1687366359.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:39.421Z,1687366359.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:39.841Z,1687366359.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:40.257Z,1687366360.257 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:40.673Z,1687366360.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:41.121Z,1687366361.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:41.537Z,1687366361.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:41.953Z,1687366361.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:42.369Z,1687366362.369 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:42.369Z,1687366362.369 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:52:42.369Z,1687366362.369 [AHRS_M2] Communications Fault, FailCount= 4 2023-06-21T16:52:42.369Z,1687366362.369 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:52:42.435Z,1687366362.435 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:52:42.455Z,1687366362.455 [AHRS_M2](INFO): Powering down 2023-06-21T16:52:43.304Z,1687366363.304 [CBIT](INFO): Clearing failed state for component AHRS_M2 2023-06-21T16:52:43.304Z,1687366363.304 [AHRS_M2] No Fault, FailCount= 4 2023-06-21T16:52:43.659Z,1687366363.659 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:52:47.217Z,1687366367.217 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:47.649Z,1687366367.649 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:48.153Z,1687366368.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:48.569Z,1687366368.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:48.985Z,1687366368.985 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:49.401Z,1687366369.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:49.817Z,1687366369.817 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:50.253Z,1687366370.253 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:50.677Z,1687366370.677 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:51.105Z,1687366371.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:51.545Z,1687366371.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:51.969Z,1687366371.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:52.385Z,1687366372.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:52.793Z,1687366372.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:53.245Z,1687366373.245 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:53.673Z,1687366373.673 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:54.117Z,1687366374.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:54.553Z,1687366374.553 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:54.969Z,1687366374.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:55.397Z,1687366375.397 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:55.837Z,1687366375.837 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:56.269Z,1687366376.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:56.697Z,1687366376.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:52:56.697Z,1687366376.697 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:52:56.697Z,1687366376.697 [AHRS_M2] Communications Fault, FailCount= 5 2023-06-21T16:52:56.697Z,1687366376.697 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:52:56.715Z,1687366376.715 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:52:56.715Z,1687366376.715 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2023-06-21T16:52:56.778Z,1687366376.778 [AHRS_M2](INFO): Powering down 2023-06-21T16:52:56.875Z,1687366376.875 [CommandExec](FAULT): Scheduling is paused 2023-06-21T16:52:56.876Z,1687366376.876 [CBIT](INFO): Critical error at 20230621T165256 2023-06-21T16:52:56.876Z,1687366376.876 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2023-06-21T16:53:35.131Z,1687366415.131 [SBIT](IMPORTANT): Beginning Startup BIT 2023-06-21T16:53:35.135Z,1687366415.135 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-21T16:53:46.033Z,1687366426.033 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-06-21T16:53:46.033Z,1687366426.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-06-21T16:53:46.033Z,1687366426.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-21T16:53:46.094Z,1687366426.094 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-21T16:53:46.098Z,1687366426.098 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.019555 CHAN A1 (24V): 0.001827 CHAN A2 (12V): -0.006462 CHAN A3 (5V): -0.003925 CHAN B0 (3.3V): -0.001607 CHAN B1 (3.15aV): -0.001085 CHAN B2 (3.15bV): -0.001259 CHAN B3 (GND): -0.000822 OPEN: 0.000442 Full Scale: +/- 1 mA 2023-06-21T16:53:46.474Z,1687366426.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-21T16:53:46.474Z,1687366426.474 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-06-21T16:54:29.287Z,1687366469.287 [SBIT](IMPORTANT): SBIT PASSED 2023-06-21T16:54:29.287Z,1687366469.287 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-06-21T16:54:29.288Z,1687366469.288 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2023-06-21T16:54:29.288Z,1687366469.288 [SBIT](IMPORTANT): DAT.maxAckTimeouts=1 count; 2023-06-21T16:54:29.289Z,1687366469.289 [SBIT](IMPORTANT): DAT.verbosity=1 count; 2023-06-21T16:54:29.289Z,1687366469.289 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool; 2023-06-21T16:54:29.309Z,1687366469.309 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2023-06-21T16:54:29.309Z,1687366469.309 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2023-06-21T16:54:29.309Z,1687366469.309 [SBIT](IMPORTANT): Depth_Keller.offset=-0.2 decibar; 2023-06-21T16:54:29.309Z,1687366469.309 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): Express none TrackAcousticContact.azimuth_to_contact_vehicle_frame; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): Express none TrackAcousticContact.range_to_contact; 2023-06-21T16:54:29.310Z,1687366469.310 [SBIT](IMPORTANT): Express none VerticalHomogeneityIndexCalculator.vertical_salinity_homogeneity_index; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express none VerticalHomogeneityIndexCalculator.vertical_temperature_homogeneity_index; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff470nm; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express none WetLabsBB2FL.BackscatteringCoeff650nm; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2023-06-21T16:54:29.311Z,1687366469.311 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=171.613872 cubic_centimeter; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): VerticalControl.massDefault=22.341465 millimeter; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter; 2023-06-21T16:54:29.312Z,1687366469.312 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool; 2023-06-21T16:54:29.682Z,1687366469.682 [MissionManager](IMPORTANT): Started mission Startup 2023-06-21T16:54:29.683Z,1687366469.683 [Startup] Running Loop=1 2023-06-21T16:54:29.683Z,1687366469.683 [Startup](DEBUG): Aggregate::initialize Startup 2023-06-21T16:54:29.683Z,1687366469.683 [Startup:A.GoToSurface] Running Loop=1 2023-06-21T16:54:29.683Z,1687366469.683 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-21T16:54:29.683Z,1687366469.683 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-21T16:54:29.684Z,1687366469.684 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-21T16:54:29.684Z,1687366469.684 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-21T16:54:29.685Z,1687366469.685 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-21T16:54:29.685Z,1687366469.685 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-21T16:54:29.686Z,1687366469.686 [Startup:StartupSatComms] Running Loop=1 2023-06-21T16:54:29.686Z,1687366469.686 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-06-21T16:54:29.687Z,1687366469.687 [Startup:StartupSatComms:A] Running Loop=1 2023-06-21T16:54:30.086Z,1687366470.086 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-06-21T16:54:54.712Z,1687366494.712 [CommandExec](IMPORTANT): got command get depth 2023-06-21T16:54:54.712Z,1687366494.712 [CommandExec](IMPORTANT): depth 0.092275 m 2023-06-21T16:54:57.938Z,1687366497.938 [CommandExec](IMPORTANT): got command failComponent 2023-06-21T16:54:57.938Z,1687366497.938 [CommandExec](IMPORTANT): Failed components: 2023-06-21T16:54:57.938Z,1687366497.938 [CommandExec](IMPORTANT): AHRS_M2: Communications Fault 2023-06-21T16:55:03.569Z,1687366503.569 [CommandExec](IMPORTANT): got command failComponent none AHRS_M2 2023-06-21T16:55:03.569Z,1687366503.569 [AHRS_M2] No Fault, FailCount= 5 2023-06-21T16:55:03.570Z,1687366503.570 [CommandExec](IMPORTANT): AHRS_M2 failureMode is No Fault 2023-06-21T16:55:03.595Z,1687366503.595 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-21T16:55:07.145Z,1687366507.145 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:07.625Z,1687366507.625 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:08.021Z,1687366508.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:08.433Z,1687366508.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:08.853Z,1687366508.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:09.273Z,1687366509.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:09.681Z,1687366509.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:10.105Z,1687366510.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:10.505Z,1687366510.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:10.933Z,1687366510.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:11.377Z,1687366511.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:11.809Z,1687366511.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:12.237Z,1687366512.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:12.665Z,1687366512.665 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:13.081Z,1687366513.081 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:13.493Z,1687366513.493 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:13.909Z,1687366513.909 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:14.329Z,1687366514.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:14.753Z,1687366514.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:15.177Z,1687366515.177 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:15.601Z,1687366515.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:16.021Z,1687366516.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:16.521Z,1687366516.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2023-06-21T16:55:16.521Z,1687366516.521 [AHRS_M2](FAULT): Failed to initialize within timeout. 2023-06-21T16:55:16.521Z,1687366516.521 [AHRS_M2] Communications Fault, FailCount= 6 2023-06-21T16:55:16.521Z,1687366516.521 [AHRS_M2](ERROR): Communications Fault 2023-06-21T16:55:16.602Z,1687366516.602 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2023-06-21T16:55:16.603Z,1687366516.603 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2023-06-21T16:55:16.667Z,1687366516.667 [AHRS_M2](INFO): Powering down 2023-06-21T16:55:16.793Z,1687366516.793 [CBIT](INFO): Critical error at 20230621T165516 2023-06-21T16:55:22.631Z,1687366522.631 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004563 2023-06-21T16:55:29.471Z,1687366529.471 [CommandExec](IMPORTANT): got command strobe off 2023-06-21T16:55:29.471Z,1687366529.471 [CommandExec](IMPORTANT): Deactivating strobe 2023-06-21T16:55:29.961Z,1687366529.961 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-21T16:54:29.7Z 2023-06-21T16:55:29.961Z,1687366529.961 [Startup:StartupSatComms:A] Stopped 2023-06-21T16:55:29.961Z,1687366529.961 [Startup:StartupSatComms:B] Running Loop=1 2023-06-21T16:55:30.373Z,1687366530.373 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-21T16:55:33.291Z,1687366533.291 [CommandExec](IMPORTANT): got command get depth 2023-06-21T16:55:33.292Z,1687366533.292 [CommandExec](IMPORTANT): depth 0.086441 m 2023-06-21T16:55:37.050Z,1687366537.050 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230620T215338/Courier0337.lzma 2023-06-21T16:55:38.052Z,1687366538.052 [DataOverHttps](INFO): Moved sent file to Logs/20230620T215338/Courier0337.lzma.bak 2023-06-21T16:55:38.052Z,1687366538.052 [DataOverHttps](INFO): SBD MOMSN=18414860 2023-06-21T16:55:46.935Z,1687366546.935 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-06-21T16:55:46.935Z,1687366546.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-06-21T16:55:46.935Z,1687366546.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-21T16:55:46.967Z,1687366546.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-21T16:55:47.348Z,1687366547.348 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-21T16:55:47.348Z,1687366547.348 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-06-21T16:55:55.617Z,1687366555.617 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20230621T165134/Courier0000.lzma 2023-06-21T16:55:57.110Z,1687366557.110 [DataOverHttps](INFO): Moved sent file to Logs/20230621T165134/Courier0000.lzma.bak 2023-06-21T16:55:57.110Z,1687366557.110 [DataOverHttps](INFO): SBD MOMSN=18414862 2023-06-21T16:56:13.369Z,1687366573.369 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20230620T215338/Express0338.lzma 2023-06-21T16:56:14.367Z,1687366574.367 [DataOverHttps](INFO): Moved sent file to Logs/20230620T215338/Express0338.lzma.bak 2023-06-21T16:56:14.367Z,1687366574.367 [DataOverHttps](INFO): SBD MOMSN=18414867 2023-06-21T16:56:29.559Z,1687366589.559 [DataOverHttps](INFO): Sending 1532 bytes from file Logs/20230621T165134/Express0001.lzma 2023-06-21T16:56:30.225Z,1687366590.225 [Startup:StartupSatComms:B](INFO): Timed out from 2023-06-21T16:55:29.0Z 2023-06-21T16:56:30.225Z,1687366590.225 [Startup:StartupSatComms:B] Stopped 2023-06-21T16:56:30.226Z,1687366590.226 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-06-21T16:56:30.226Z,1687366590.226 [Startup:StartupSatComms] Stopped 2023-06-21T16:56:30.226Z,1687366590.226 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-06-21T16:56:30.227Z,1687366590.227 [Startup](INFO): Completed Startup 2023-06-21T16:56:30.227Z,1687366590.227 [MissionManager](INFO): Startup is completed. 2023-06-21T16:56:30.227Z,1687366590.227 [MissionManager](INFO): Uninitializing Mission Startup 2023-06-21T16:56:30.227Z,1687366590.227 [Startup] Stopped 2023-06-21T16:56:30.227Z,1687366590.227 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-06-21T16:56:30.227Z,1687366590.227 [Startup:A.GoToSurface] Stopped 2023-06-21T16:56:30.227Z,1687366590.227 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-21T16:56:30.559Z,1687366590.559 [DataOverHttps](INFO): Moved sent file to Logs/20230621T165134/Express0001.lzma.bak 2023-06-21T16:56:30.559Z,1687366590.559 [DataOverHttps](INFO): SBD MOMSN=18414871 2023-06-21T16:56:30.612Z,1687366590.612 [MissionManager](IMPORTANT): Started mission Default 2023-06-21T16:56:30.612Z,1687366590.612 [Default] Running Loop=1 2023-06-21T16:56:30.612Z,1687366590.612 [Default](DEBUG): Aggregate::initialize Default 2023-06-21T16:56:30.612Z,1687366590.612 [Default:B.GoToSurface] Running Loop=1 2023-06-21T16:56:30.612Z,1687366590.612 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-21T16:56:30.613Z,1687366590.613 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-21T16:56:30.613Z,1687366590.613 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-21T16:56:30.613Z,1687366590.613 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-21T16:56:30.614Z,1687366590.614 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-21T16:56:30.614Z,1687366590.614 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-21T16:56:30.614Z,1687366590.614 [Default:A.Wait] Running Loop=1 2023-06-21T16:56:30.614Z,1687366590.614 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-06-21T16:56:43.942Z,1687366603.942 [Default:A.Wait](INFO): Done Waiting. 2023-06-21T16:56:43.942Z,1687366603.942 [Default:A.Wait] Stopped 2023-06-21T16:56:43.942Z,1687366603.942 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-06-21T16:56:44.352Z,1687366604.352 [Default:CheckIn] Running Loop=1 2023-06-21T16:56:44.352Z,1687366604.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-21T16:56:44.352Z,1687366604.352 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-21T16:56:44.753Z,1687366604.753 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-06-21T16:57:29.648Z,1687366649.648 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-21T16:57:47.781Z,1687366667.781 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2023-06-21T16:57:47.782Z,1687366667.782 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-06-21T16:57:47.782Z,1687366667.782 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-21T16:57:47.792Z,1687366667.792 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-21T16:57:48.291Z,1687366668.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-21T16:57:48.291Z,1687366668.291 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-06-21T16:58:20.432Z,1687366700.432 [CommandExec](IMPORTANT): got command get latitude 2023-06-21T16:58:20.432Z,1687366700.432 [CommandExec](FAULT): Element has no value 2023-06-21T16:58:23.042Z,1687366703.042 [CommandExec](IMPORTANT): got command restart application 2023-06-21T16:58:24.045Z,1687366704.045 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:24.045Z,1687366704.045 [CommandExec](INFO): Uninitializing the command executive. 2023-06-21T16:58:24.045Z,1687366704.045 [CommandExec](INFO): Uninitializing the command scheduler. 2023-06-21T16:58:24.046Z,1687366704.046 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.189Z,1687366704.189 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2023-06-21T16:58:24.189Z,1687366704.189 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2023-06-21T16:58:24.189Z,1687366704.189 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.190Z,1687366704.190 [NavChartDb](INFO): Join timeout helper Thread ID is 996 2023-06-21T16:58:24.245Z,1687366704.245 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:24.245Z,1687366704.245 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.249Z,1687366704.249 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2023-06-21T16:58:24.249Z,1687366704.249 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.250Z,1687366704.250 [Radio_Surface](INFO): Join timeout helper Thread ID is 997 2023-06-21T16:58:24.621Z,1687366704.621 [Radio_Surface](INFO): Powering down 2023-06-21T16:58:24.622Z,1687366704.622 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:24.622Z,1687366704.622 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.629Z,1687366704.629 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2023-06-21T16:58:24.629Z,1687366704.629 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:24.629Z,1687366704.629 [Onboard](INFO): Join timeout helper Thread ID is 998 2023-06-21T16:58:25.505Z,1687366705.505 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2023-06-21T16:58:26.002Z,1687366706.002 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:26.002Z,1687366706.002 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.021Z,1687366706.021 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2023-06-21T16:58:26.021Z,1687366706.021 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.021Z,1687366706.021 [DataOverHttps](INFO): Join timeout helper Thread ID is 999 2023-06-21T16:58:26.509Z,1687366706.509 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:26.509Z,1687366706.509 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.532Z,1687366706.532 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2023-06-21T16:58:26.532Z,1687366706.532 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.532Z,1687366706.532 [DAT](INFO): Join timeout helper Thread ID is 1000 2023-06-21T16:58:26.661Z,1687366706.661 [DAT](INFO): Powering down 2023-06-21T16:58:26.734Z,1687366706.734 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:26.734Z,1687366706.734 [DAT](INFO): Powering down 2023-06-21T16:58:26.735Z,1687366706.735 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.750Z,1687366706.750 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2023-06-21T16:58:26.750Z,1687366706.750 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.750Z,1687366706.750 [BackseatComponent](INFO): Join timeout helper Thread ID is 1001 2023-06-21T16:58:26.849Z,1687366706.849 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:26.850Z,1687366706.850 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.862Z,1687366706.862 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2023-06-21T16:58:26.862Z,1687366706.862 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.862Z,1687366706.862 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1002 2023-06-21T16:58:26.938Z,1687366706.938 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:26.938Z,1687366706.938 [WetLabsBB2FL](INFO): Powering down 2023-06-21T16:58:26.938Z,1687366706.938 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.954Z,1687366706.954 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2023-06-21T16:58:26.954Z,1687366706.954 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:26.954Z,1687366706.954 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1003 2023-06-21T16:58:27.269Z,1687366707.269 [CTD_Seabird](INFO): Powering down 2023-06-21T16:58:27.281Z,1687366707.281 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:27.281Z,1687366707.281 [CTD_Seabird](INFO): Powering down 2023-06-21T16:58:27.293Z,1687366707.293 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.315Z,1687366707.315 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2023-06-21T16:58:27.315Z,1687366707.315 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.315Z,1687366707.315 [logger](INFO): Join timeout helper Thread ID is 1004 2023-06-21T16:58:27.336Z,1687366707.336 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:27.336Z,1687366707.336 [logger ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.358Z,1687366707.358 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2023-06-21T16:58:27.358Z,1687366707.358 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.358Z,1687366707.358 [CommandLine](INFO): Join timeout helper Thread ID is 1005 2023-06-21T16:58:27.401Z,1687366707.401 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:27.401Z,1687366707.401 [CommandLine ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.410Z,1687366707.410 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2023-06-21T16:58:27.410Z,1687366707.410 [CommandExec ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.410Z,1687366707.410 [CommandExec](INFO): Join timeout helper Thread ID is 1006 2023-06-21T16:58:27.411Z,1687366707.411 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2023-06-21T16:58:27.411Z,1687366707.411 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.412Z,1687366707.412 [controlThread](INFO): Join timeout helper Thread ID is 1007 2023-06-21T16:58:27.749Z,1687366707.749 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2023-06-21T16:58:27.749Z,1687366707.749 [controlThread](DEBUG): Uninitializing ControlThread 2023-06-21T16:58:27.751Z,1687366707.751 [AHRS_M2](INFO): Powering down 2023-06-21T16:58:27.753Z,1687366707.753 [NAL9602](INFO): Powering down 2023-06-21T16:58:27.754Z,1687366707.754 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2023-06-21T16:58:27.755Z,1687366707.755 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2023-06-21T16:58:27.755Z,1687366707.755 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2023-06-21T16:58:27.756Z,1687366707.756 [MissionManager](INFO): Uninitializing Mission Default 2023-06-21T16:58:27.756Z,1687366707.756 [Default] Stopped 2023-06-21T16:58:27.756Z,1687366707.756 [Default](DEBUG): Aggregate::uninitialize Default 2023-06-21T16:58:27.756Z,1687366707.756 [Default:B.GoToSurface] Stopped 2023-06-21T16:58:27.756Z,1687366707.756 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-21T16:58:27.756Z,1687366707.756 [Default:CheckIn] Stopped 2023-06-21T16:58:27.756Z,1687366707.756 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-21T16:58:27.756Z,1687366707.756 [Default:CheckIn:Read_GPS] Stopped 2023-06-21T16:58:27.759Z,1687366707.759 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2023-06-21T16:58:27.759Z,1687366707.759 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2023-06-21T16:58:27.759Z,1687366707.759 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2023-06-21T16:58:27.759Z,1687366707.759 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2023-06-21T16:58:27.760Z,1687366707.760 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2023-06-21T16:58:27.760Z,1687366707.760 [BuoyancyServo](INFO): Powering down 2023-06-21T16:58:27.773Z,1687366707.773 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2023-06-21T16:58:27.773Z,1687366707.773 [ElevatorServo](INFO): Powering down 2023-06-21T16:58:27.774Z,1687366707.774 [MassServo](DEBUG): Uninitialize Mass Servo. 2023-06-21T16:58:27.774Z,1687366707.774 [MassServo](INFO): Powering down 2023-06-21T16:58:27.775Z,1687366707.775 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2023-06-21T16:58:27.775Z,1687366707.775 [RudderServo](INFO): Powering down 2023-06-21T16:58:27.776Z,1687366707.776 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2023-06-21T16:58:27.776Z,1687366707.776 [ThrusterHE](INFO): Powering down 2023-06-21T16:58:27.777Z,1687366707.777 [SBIT](DEBUG): Uninitialize SBIT Component. 2023-06-21T16:58:27.777Z,1687366707.777 [IBIT](DEBUG): Uninitialize IBIT Component. 2023-06-21T16:58:27.778Z,1687366707.778 [CBIT](DEBUG): Uninitialize CBIT Component. 2023-06-21T16:58:27.778Z,1687366707.778 [CBIT](DEBUG): Powering off loads. 2023-06-21T16:58:27.789Z,1687366707.789 [CBIT](DEBUG): Disabling WDT. 2023-06-21T16:58:27.801Z,1687366707.801 [CBIT](DEBUG): Opening all GF detection circuits. 2023-06-21T16:58:27.802Z,1687366707.802 [controlThread ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.840Z,1687366707.840 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.846Z,1687366707.846 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.874Z,1687366707.874 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.876Z,1687366707.876 [Onboard ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.882Z,1687366707.882 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.888Z,1687366707.888 [DAT ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.929Z,1687366707.929 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:27.979Z,1687366707.979 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2023-06-21T16:58:28.027Z,1687366708.027 [logger ThreadHandler](INFO): Thread cancelled.