2025-02-06T01:46:51.212Z,1738806411.212 [Supervisor](DEBUG): Initializing supervisor. 2025-02-06T01:46:51.216Z,1738806411.216 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-02-06T01:46:51.216Z,1738806411.216 [SyncHandler](INFO): Protected caller Thread ID is 837 2025-02-06T01:46:51.217Z,1738806411.217 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-02-06T01:46:51.218Z,1738806411.218 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-02-06T01:46:51.218Z,1738806411.218 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838 2025-02-06T01:46:51.222Z,1738806411.222 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-02-06T01:46:51.239Z,1738806411.239 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-02-06T01:46:51.240Z,1738806411.240 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-02-06T01:46:51.240Z,1738806411.240 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839 2025-02-06T01:46:51.244Z,1738806411.244 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-02-06T01:46:51.245Z,1738806411.245 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-02-06T01:46:51.246Z,1738806411.246 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840 2025-02-06T01:46:51.248Z,1738806411.248 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-02-06T01:46:51.249Z,1738806411.249 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-02-06T01:46:51.249Z,1738806411.249 [logger ThreadHandler](INFO): Protected caller Thread ID is 841 2025-02-06T01:46:51.253Z,1738806411.253 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-02-06T01:46:51.253Z,1738806411.253 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-02-06T01:46:51.257Z,1738806411.257 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-02-06T01:46:51.494Z,1738806411.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-02-06T01:46:51.496Z,1738806411.496 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-02-06T01:46:51.713Z,1738806411.713 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-02-06T01:46:51.715Z,1738806411.715 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-02-06T01:46:51.820Z,1738806411.820 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-02-06T01:46:51.821Z,1738806411.821 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-02-06T01:46:52.011Z,1738806412.011 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-02-06T01:46:52.013Z,1738806412.013 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-02-06T01:46:52.508Z,1738806412.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-02-06T01:46:52.510Z,1738806412.510 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-02-06T01:46:52.627Z,1738806412.627 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-02-06T01:46:52.629Z,1738806412.629 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-02-06T01:46:52.921Z,1738806412.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-02-06T01:46:52.923Z,1738806412.923 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-02-06T01:46:53.142Z,1738806413.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-02-06T01:46:53.143Z,1738806413.143 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-02-06T01:46:53.685Z,1738806413.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-02-06T01:46:53.685Z,1738806413.685 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-02-06T01:46:54.104Z,1738806414.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-02-06T01:46:54.105Z,1738806414.105 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-02-06T01:46:54.195Z,1738806414.195 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-02-06T01:46:54.584Z,1738806414.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-02-06T01:46:54.585Z,1738806414.585 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-02-06T01:46:55.710Z,1738806415.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-02-06T01:46:55.711Z,1738806415.711 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-02-06T01:47:03.926Z,1738806423.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-02-06T01:47:03.926Z,1738806423.926 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-02-06T01:47:04.886Z,1738806424.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-02-06T01:47:04.888Z,1738806424.888 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-02-06T01:47:04.981Z,1738806424.981 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2025-02-06T01:47:04.983Z,1738806424.983 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2025-02-06T01:47:05.076Z,1738806425.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2025-02-06T01:47:05.190Z,1738806425.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2025-02-06T01:47:05.303Z,1738806425.303 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2025-02-06T01:47:05.401Z,1738806425.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2025-02-06T01:47:05.496Z,1738806425.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2025-02-06T01:47:05.609Z,1738806425.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2025-02-06T01:47:05.760Z,1738806425.760 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2025-02-06T01:47:05.934Z,1738806425.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2025-02-06T01:47:06.038Z,1738806426.038 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2025-02-06T01:47:06.310Z,1738806426.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-02-06T01:47:06.311Z,1738806426.311 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-02-06T01:47:06.316Z,1738806426.316 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-02-06T01:47:06.735Z,1738806426.735 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-02-06T01:47:06.737Z,1738806426.737 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-02-06T01:47:07.560Z,1738806427.560 [AHRS_M2] Loaded 2025-02-06T01:47:07.561Z,1738806427.561 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-02-06T01:47:07.813Z,1738806427.813 [BackseatComponent] Loaded 2025-02-06T01:47:07.813Z,1738806427.813 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-02-06T01:47:07.814Z,1738806427.814 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408AB4E0 2025-02-06T01:47:07.814Z,1738806427.814 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 921 2025-02-06T01:47:07.817Z,1738806427.817 [LcmUniversalReporter] Loaded 2025-02-06T01:47:07.817Z,1738806427.817 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-02-06T01:47:07.824Z,1738806427.824 [BioacousticsDataBridge] Loaded 2025-02-06T01:47:07.824Z,1738806427.824 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2025-02-06T01:47:08.612Z,1738806428.612 [BPC1] Loaded 2025-02-06T01:47:08.612Z,1738806428.612 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-02-06T01:47:08.705Z,1738806428.705 [DAT] Loaded 2025-02-06T01:47:08.705Z,1738806428.705 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-02-06T01:47:08.706Z,1738806428.706 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408DB4E0 2025-02-06T01:47:08.707Z,1738806428.707 [DAT ThreadHandler](INFO): Protected caller Thread ID is 922 2025-02-06T01:47:08.846Z,1738806428.846 [DataOverHttps] Loaded 2025-02-06T01:47:08.846Z,1738806428.846 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-02-06T01:47:08.847Z,1738806428.847 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0 2025-02-06T01:47:08.848Z,1738806428.848 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923 2025-02-06T01:47:08.870Z,1738806428.870 [Depth_Keller] Loaded 2025-02-06T01:47:08.870Z,1738806428.870 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-02-06T01:47:08.875Z,1738806428.875 [DropWeight] Loaded 2025-02-06T01:47:08.875Z,1738806428.875 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-02-06T01:47:08.938Z,1738806428.938 [NAL9602] Loaded 2025-02-06T01:47:08.938Z,1738806428.938 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-02-06T01:47:08.967Z,1738806428.967 [Onboard] Loaded 2025-02-06T01:47:08.968Z,1738806428.968 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-02-06T01:47:08.969Z,1738806428.969 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2025-02-06T01:47:08.969Z,1738806428.969 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924 2025-02-06T01:47:08.982Z,1738806428.982 [Power24vConverter] Loaded 2025-02-06T01:47:08.983Z,1738806428.983 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-02-06T01:47:08.999Z,1738806428.999 [Radio_Surface] Loaded 2025-02-06T01:47:08.999Z,1738806428.999 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-02-06T01:47:09.000Z,1738806429.000 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0 2025-02-06T01:47:09.001Z,1738806429.001 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925 2025-02-06T01:47:09.016Z,1738806429.016 [Sonardyne_Nano] Loaded 2025-02-06T01:47:09.016Z,1738806429.016 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-02-06T01:47:09.016Z,1738806429.016 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-02-06T01:47:09.017Z,1738806429.017 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-02-06T01:47:13.783Z,1738806433.783 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-02-06T01:47:13.784Z,1738806433.784 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-02-06T01:47:15.501Z,1738806435.501 [VerticalControl](DEBUG): Construct VerticalControl. 2025-02-06T01:47:15.560Z,1738806435.560 [VerticalControl] Loaded 2025-02-06T01:47:15.561Z,1738806435.561 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-02-06T01:47:15.564Z,1738806435.564 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-02-06T01:47:15.611Z,1738806435.611 [HorizontalControl] Loaded 2025-02-06T01:47:15.611Z,1738806435.611 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-02-06T01:47:15.613Z,1738806435.613 [SpeedControl](DEBUG): Construct SpeedControl. 2025-02-06T01:47:15.616Z,1738806435.616 [SpeedControl] Loaded 2025-02-06T01:47:15.617Z,1738806435.617 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-02-06T01:47:15.619Z,1738806435.619 [LoopControl](DEBUG): Construct LoopControl. 2025-02-06T01:47:15.620Z,1738806435.620 [LoopControl] Loaded 2025-02-06T01:47:15.620Z,1738806435.620 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-02-06T01:47:15.621Z,1738806435.621 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-02-06T01:47:15.621Z,1738806435.621 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-02-06T01:47:15.637Z,1738806435.637 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-02-06T01:47:15.638Z,1738806435.638 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-02-06T01:47:15.688Z,1738806435.688 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-02-06T01:47:15.689Z,1738806435.689 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-02-06T01:47:15.838Z,1738806435.838 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-02-06T01:47:15.838Z,1738806435.838 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-02-06T01:47:15.851Z,1738806435.851 [NavChart] Loaded 2025-02-06T01:47:15.852Z,1738806435.852 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-02-06T01:47:15.857Z,1738806435.857 [UniversalFixResidualReporter] Loaded 2025-02-06T01:47:15.857Z,1738806435.857 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-02-06T01:47:15.858Z,1738806435.858 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-02-06T01:47:15.859Z,1738806435.859 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-02-06T01:47:16.029Z,1738806436.029 [SBIT](DEBUG): Construct Startup Built In Test. 2025-02-06T01:47:16.038Z,1738806436.038 [SBIT] Loaded 2025-02-06T01:47:16.038Z,1738806436.038 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-02-06T01:47:16.041Z,1738806436.041 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-02-06T01:47:16.054Z,1738806436.054 [IBIT] Loaded 2025-02-06T01:47:16.054Z,1738806436.054 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-02-06T01:47:16.060Z,1738806436.060 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-02-06T01:47:16.164Z,1738806436.164 [CBIT] Loaded 2025-02-06T01:47:16.164Z,1738806436.164 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-02-06T01:47:16.165Z,1738806436.165 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-02-06T01:47:16.165Z,1738806436.165 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-02-06T01:47:16.382Z,1738806436.382 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-02-06T01:47:16.383Z,1738806436.383 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-02-06T01:47:16.547Z,1738806436.547 [BuoyancyServo] Loaded 2025-02-06T01:47:16.547Z,1738806436.547 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-02-06T01:47:16.570Z,1738806436.570 [ElevatorServo] Loaded 2025-02-06T01:47:16.570Z,1738806436.570 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-02-06T01:47:16.591Z,1738806436.591 [MassServo] Loaded 2025-02-06T01:47:16.591Z,1738806436.591 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-02-06T01:47:16.612Z,1738806436.612 [RudderServo] Loaded 2025-02-06T01:47:16.612Z,1738806436.612 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-02-06T01:47:16.629Z,1738806436.629 [ThrusterHE] Loaded 2025-02-06T01:47:16.629Z,1738806436.629 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-02-06T01:47:16.630Z,1738806436.630 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-02-06T01:47:16.630Z,1738806436.630 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-02-06T01:47:16.871Z,1738806436.871 [CTD_Seabird] Loaded 2025-02-06T01:47:16.871Z,1738806436.871 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-02-06T01:47:16.873Z,1738806436.873 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B9C4E0 2025-02-06T01:47:16.873Z,1738806436.873 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926 2025-02-06T01:47:16.895Z,1738806436.895 [PAR_Licor] Loaded 2025-02-06T01:47:16.895Z,1738806436.895 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-02-06T01:47:16.939Z,1738806436.939 [WetLabsBB2FL] Loaded 2025-02-06T01:47:16.939Z,1738806436.939 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-02-06T01:47:16.941Z,1738806436.941 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BCC4E0 2025-02-06T01:47:16.941Z,1738806436.941 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927 2025-02-06T01:47:16.942Z,1738806436.942 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-02-06T01:47:16.942Z,1738806436.942 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-02-06T01:47:17.014Z,1738806437.014 [DepthRateCalculator] Loaded 2025-02-06T01:47:17.014Z,1738806437.014 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-02-06T01:47:17.019Z,1738806437.019 [PitchRateCalculator] Loaded 2025-02-06T01:47:17.019Z,1738806437.019 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-02-06T01:47:17.028Z,1738806437.028 [SpeedCalculator] Loaded 2025-02-06T01:47:17.029Z,1738806437.029 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-02-06T01:47:17.033Z,1738806437.033 [YawRateCalculator] Loaded 2025-02-06T01:47:17.033Z,1738806437.033 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-02-06T01:47:17.052Z,1738806437.052 [ElevatorOffsetCalculator] Loaded 2025-02-06T01:47:17.053Z,1738806437.053 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-02-06T01:47:17.053Z,1738806437.053 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-02-06T01:47:17.054Z,1738806437.054 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-02-06T01:47:17.139Z,1738806437.139 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-02-06T01:47:17.145Z,1738806437.145 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-02-06T01:47:17.148Z,1738806437.148 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-02-06T01:47:17.159Z,1738806437.159 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-02-06T01:47:17.160Z,1738806437.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C594E0 2025-02-06T01:47:17.161Z,1738806437.161 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928 2025-02-06T01:47:17.165Z,1738806437.165 [Supervisor](INFO): Main Thread ID is 828 2025-02-06T01:47:17.165Z,1738806437.165 [Supervisor](DEBUG): Running supervisor. 2025-02-06T01:47:17.166Z,1738806437.166 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929 2025-02-06T01:47:17.166Z,1738806437.166 [CommandExec](INFO): Initializing the command executive. 2025-02-06T01:47:17.168Z,1738806437.168 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930 2025-02-06T01:47:17.170Z,1738806437.170 [controlThread ThreadHandler](INFO): Handler Thread ID is 931 2025-02-06T01:47:17.170Z,1738806437.170 [controlThread](DEBUG): Initializing ControlThread 2025-02-06T01:47:17.178Z,1738806437.178 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-02-06T01:47:17.179Z,1738806437.179 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-02-06T01:47:17.180Z,1738806437.180 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-02-06T01:47:17.181Z,1738806437.181 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-02-06T01:47:17.181Z,1738806437.181 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-02-06T01:47:17.182Z,1738806437.182 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-02-06T01:47:17.182Z,1738806437.182 [SBIT](INFO): Initialize SBIT Component. 2025-02-06T01:47:17.182Z,1738806437.182 [SBIT](IMPORTANT): git: 2025-02-05 2025-02-06T01:47:17.183Z,1738806437.183 [SBIT](INFO): git hash: a4c59949123439bf87ea957d0729f09aa77b2fd6 2025-02-06T01:47:17.183Z,1738806437.183 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-02-06T01:47:17.184Z,1738806437.184 [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 Dec 4 01:17:04 UTC 2024 2025-02-06T01:47:17.185Z,1738806437.185 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2025-02-06T01:47:17.186Z,1738806437.186 [IBIT](INFO): Initialize IBIT Component. 2025-02-06T01:47:17.187Z,1738806437.187 [CBIT](DEBUG): Initialize CBIT Component. 2025-02-06T01:47:17.188Z,1738806437.188 [logger ThreadHandler](INFO): Handler Thread ID is 932 2025-02-06T01:47:17.200Z,1738806437.200 [CBIT](DEBUG): Initialized mux pins. 2025-02-06T01:47:17.200Z,1738806437.200 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-02-06T01:47:17.200Z,1738806437.200 [CBIT](DEBUG): Initializing the watchdog timer. 2025-02-06T01:47:17.208Z,1738806437.208 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 933 2025-02-06T01:47:17.220Z,1738806437.220 [DAT ThreadHandler](INFO): Handler Thread ID is 934 2025-02-06T01:47:17.221Z,1738806437.221 [DAT](INFO): Powering up 2025-02-06T01:47:17.221Z,1738806437.221 [DAT](DEBUG): Initializing DAT. 2025-02-06T01:47:17.224Z,1738806437.224 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-02-06T01:47:17.227Z,1738806437.227 [CBIT](FAULT): Found backtrace file: 1738806258.911040 [CommandExec]: SIGSEGV: address not mapped to object 1738806258.911040 [CommandExec]: Backtrace: ./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3db8] ./bin/LRAUV(_ZN13ParsedCommand7executeER6Logger+0x38) [0x1b894c] ./bin/LRAUV(_ZN11CommandExec19processCommandQueueERK8Timespan+0x84) [0x1bbccc] ./bin/LRAUV(_ZN11CommandExec3runEv+0x2f4) [0x1c3b80] ./bin/LRAUV(_ZN9Component15executeRunStateEv+0x5c) [0xeefd0] ./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1b3804] 2025-02-06T01:47:17.229Z,1738806437.229 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936 2025-02-06T01:47:17.231Z,1738806437.231 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-02-06T01:47:17.233Z,1738806437.233 [CBIT](FAULT): Renaming backtrace file to Data/backtrace3 2025-02-06T01:47:17.233Z,1738806437.233 [CBIT](DEBUG): Initializing heartbeat. 2025-02-06T01:47:17.252Z,1738806437.252 [Onboard ThreadHandler](INFO): Handler Thread ID is 937 2025-02-06T01:47:17.270Z,1738806437.270 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938 2025-02-06T01:47:17.288Z,1738806437.288 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939 2025-02-06T01:47:17.289Z,1738806437.289 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-02-06T01:47:17.293Z,1738806437.293 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941 2025-02-06T01:47:17.296Z,1738806437.296 [WetLabsBB2FL](INFO): Powering up 2025-02-06T01:47:17.298Z,1738806437.298 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943 2025-02-06T01:47:17.304Z,1738806437.304 [CBIT](DEBUG): Deactivating GF circuits. 2025-02-06T01:47:17.304Z,1738806437.304 [CBIT](DEBUG): Deactivating emergency mode. 2025-02-06T01:47:17.309Z,1738806437.309 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-02-06T01:47:17.310Z,1738806437.310 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-02-06T01:47:17.344Z,1738806437.344 [CBIT](DEBUG): Backplane powered. 2025-02-06T01:47:17.348Z,1738806437.348 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-02-06T01:47:17.349Z,1738806437.349 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-02-06T01:47:17.349Z,1738806437.349 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-02-06T01:47:17.349Z,1738806437.349 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-02-06T01:47:17.350Z,1738806437.350 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-02-06T01:47:17.351Z,1738806437.351 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-02-06T01:47:17.351Z,1738806437.351 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-02-06T01:47:17.352Z,1738806437.352 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-02-06T01:47:17.358Z,1738806437.358 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-02-06T01:47:17.433Z,1738806437.433 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-02-06T01:47:17.453Z,1738806437.453 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-06T01:47:17.481Z,1738806437.481 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-02-06T01:47:17.482Z,1738806437.482 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-02-06T01:47:17.482Z,1738806437.482 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-02-06T01:47:17.492Z,1738806437.492 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-02-06T01:47:17.676Z,1738806437.676 [Radio_Surface](INFO): Powering up 2025-02-06T01:47:17.777Z,1738806437.777 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-02-06T01:47:17.799Z,1738806437.799 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-02-06T01:47:17.801Z,1738806437.801 [Default:A.Wait](DEBUG): Construct Wait. 2025-02-06T01:47:17.803Z,1738806437.803 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-02-06T01:47:17.857Z,1738806437.857 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-02-06T01:47:17.859Z,1738806437.859 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-02-06T01:47:17.882Z,1738806437.882 [Default:E.Execute](DEBUG): Construct Execute. 2025-02-06T01:47:17.885Z,1738806437.885 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2025-02-06T01:47:17.896Z,1738806437.896 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2025-02-06T01:47:17.908Z,1738806437.908 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-02-06T01:47:18.062Z,1738806438.062 [Power24vConverter](INFO): Powering up. 2025-02-06T01:47:18.063Z,1738806438.063 [Sonardyne_Nano](INFO): Initializing. 2025-02-06T01:47:18.154Z,1738806438.154 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-02-06T01:47:18.160Z,1738806438.160 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-02-06T01:47:18.161Z,1738806438.161 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-02-06T01:47:18.168Z,1738806438.168 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-02-06T01:47:18.169Z,1738806438.169 [MassServo](DEBUG): Initializing EZServoServo. 2025-02-06T01:47:18.176Z,1738806438.176 [MassServo](DEBUG): Initializing MassServo. 2025-02-06T01:47:18.177Z,1738806438.177 [RudderServo](DEBUG): Initializing EZServoServo. 2025-02-06T01:47:18.184Z,1738806438.184 [RudderServo](DEBUG): Initializing RudderServo. 2025-02-06T01:47:18.185Z,1738806438.185 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-02-06T01:47:18.192Z,1738806438.192 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-02-06T01:47:19.436Z,1738806439.436 [WetLabsBB2FL](INFO): Powering down 2025-02-06T01:47:20.331Z,1738806440.331 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-02-06T01:47:21.580Z,1738806441.580 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409 2025-02-06T01:47:23.817Z,1738806443.817 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-02-06T01:47:29.823Z,1738806449.823 [DAT](INFO): DAT read: 2025-02-06T01:47:29.825Z,1738806449.825 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-02-06T01:47:31.589Z,1738806451.589 [DAT](INFO): DAT read: MF Frequency Band 2025-02-06T01:47:31.590Z,1738806451.590 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-02-06T01:47:31.591Z,1738806451.591 [DAT](INFO): DAT read: Feb 6 2025 00:51:42 2025-02-06T01:47:32.593Z,1738806452.593 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-02-06T01:47:32.594Z,1738806452.594 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-02-06T01:47:32.595Z,1738806452.595 [DAT](INFO): commRate: 800 2025-02-06T01:47:34.660Z,1738806454.660 [DAT](INFO): entering command mode 2025-02-06T01:47:34.861Z,1738806454.861 [DAT](INFO): DAT read: 2025-02-06T01:47:34.862Z,1738806454.862 [DAT](INFO): DAT read: user:1> 2025-02-06T01:47:34.862Z,1738806454.862 [DAT](INFO): setting verbose to 3 2025-02-06T01:47:35.113Z,1738806455.113 [DAT](INFO): DAT read: user:1> 2025-02-06T01:47:35.114Z,1738806455.114 [DAT](INFO): DAT read: Verbose | 3 2025-02-06T01:47:35.114Z,1738806455.114 [DAT](INFO): set verbose to 3 2025-02-06T01:47:35.114Z,1738806455.114 [DAT](INFO): setting DatVerbose to 27440 2025-02-06T01:47:35.365Z,1738806455.365 [DAT](INFO): DAT read: user:2> 2025-02-06T01:47:35.366Z,1738806455.366 [DAT](INFO): DAT read: DatVerbose | 27440 2025-02-06T01:47:35.366Z,1738806455.366 [DAT](INFO): set DatVerbose to 27440 2025-02-06T01:47:35.366Z,1738806455.366 [DAT](INFO): setting transmit power to 8 2025-02-06T01:47:35.617Z,1738806455.617 [DAT](INFO): DAT read: user:3> 2025-02-06T01:47:35.618Z,1738806455.618 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-02-06T01:47:35.618Z,1738806455.618 [DAT](INFO): set transmit power to 8 2025-02-06T01:47:35.618Z,1738806455.618 [DAT](INFO): setting local address to 2 2025-02-06T01:47:35.868Z,1738806455.868 [DAT](INFO): DAT read: user:4> 2025-02-06T01:47:35.869Z,1738806455.869 [DAT](INFO): DAT read: LocalAddr | 2 2025-02-06T01:47:35.870Z,1738806455.870 [DAT](INFO): set local address to 2 2025-02-06T01:47:35.871Z,1738806455.871 [DAT](INFO): Setting time to: 1:47:35 And date to:2/6/2025 2025-02-06T01:47:36.121Z,1738806456.121 [DAT](INFO): DAT read: user:5> 2025-02-06T01:47:36.122Z,1738806456.122 [DAT](INFO): DAT read: Thu Feb 6, 2025 01:47:35 2025-02-06T01:47:36.122Z,1738806456.122 [DAT](INFO): Local DAT time set to Thu Feb 6, 2025 01:47:35 2025-02-06T01:47:37.749Z,1738806457.749 [SBIT](IMPORTANT): Beginning Startup BIT 2025-02-06T01:47:37.757Z,1738806457.757 [CBIT](IMPORTANT): Beginning ground fault scan 2025-02-06T01:47:39.321Z,1738806459.321 [NAL9602](INFO): Powering up NAL9602 2025-02-06T01:47:49.934Z,1738806469.934 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.000725 CHAN A1 (24V): -0.000366 CHAN A2 (12V): 0.000098 CHAN A3 (5V): -0.001765 CHAN B0 (3.3V): -0.001023 CHAN B1 (3.15aV): -0.001178 CHAN B2 (3.15bV): -0.000979 CHAN B3 (GND): -0.001992 OPEN: 0.004720 Full Scale: +/- 1 mA 2025-02-06T01:47:50.250Z,1738806470.250 [NAL9602](INFO): NAL9602 initialized 2025-02-06T01:48:14.907Z,1738806494.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=12525, MT Status=0, MTMSN=0 2025-02-06T01:48:14.907Z,1738806494.907 [NAL9602](INFO): No messages in MT queue 2025-02-06T01:48:31.966Z,1738806511.966 [SBIT](IMPORTANT): SBIT PASSED 2025-02-06T01:48:31.966Z,1738806511.966 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-02-06T01:48:32.370Z,1738806512.370 [MissionManager](IMPORTANT): Started mission Startup 2025-02-06T01:48:32.370Z,1738806512.370 [Startup] Running Loop=1 2025-02-06T01:48:32.370Z,1738806512.370 [Startup](DEBUG): Aggregate::initialize Startup 2025-02-06T01:48:32.370Z,1738806512.370 [Startup:A.GoToSurface] Running Loop=1 2025-02-06T01:48:32.370Z,1738806512.370 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-06T01:48:32.371Z,1738806512.371 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-06T01:48:32.371Z,1738806512.371 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-06T01:48:32.371Z,1738806512.371 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-06T01:48:32.376Z,1738806512.376 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-06T01:48:32.376Z,1738806512.376 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-06T01:48:32.377Z,1738806512.377 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-06T01:48:32.378Z,1738806512.378 [Startup:StartupSatComms] Running Loop=1 2025-02-06T01:48:32.378Z,1738806512.378 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-02-06T01:48:32.379Z,1738806512.379 [Startup:StartupSatComms:A] Running Loop=1 2025-02-06T01:48:32.742Z,1738806512.742 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-02-06T01:49:04.255Z,1738806544.255 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014901.00,A,3647.40500,N,12150.53670,W,0.214,158.50,060225,,,A*7B 2025-02-06T01:49:04.259Z,1738806544.259 [NAL9602](INFO): GPS fix at 20250206T014901: (36.790083, -121.842278) 2025-02-06T01:49:04.270Z,1738806544.270 [Startup:StartupSatComms:A] Stopped 2025-02-06T01:49:04.270Z,1738806544.270 [Startup:StartupSatComms:B] Running Loop=1 2025-02-06T01:49:04.680Z,1738806544.680 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-06T01:49:32.048Z,1738806572.048 [NAL9602](INFO): SBD MO Status=2, MOMSN=12526, MT Status=2, MTMSN=0 2025-02-06T01:49:32.048Z,1738806572.048 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-02-06T01:49:33.831Z,1738806573.831 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004121 2025-02-06T01:49:41.069Z,1738806581.069 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250206T010046/Courier0043.lzma 2025-02-06T01:49:42.071Z,1738806582.071 [DataOverHttps](INFO): Moved sent file to Logs/20250206T010046/Courier0043.lzma.bak 2025-02-06T01:49:42.071Z,1738806582.071 [DataOverHttps](INFO): SBD MOMSN=23958411 2025-02-06T01:49:51.096Z,1738806591.096 [NAL9602](INFO): SBD MO Status=1, MOMSN=12526, MT Status=0, MTMSN=0 2025-02-06T01:49:51.154Z,1738806591.154 [NAL9602](INFO): Sent 18 bytes from file Logs/20250206T010046/Courier0043.lzma 2025-02-06T01:49:51.154Z,1738806591.154 [NAL9602](INFO): Packets left to send: 0 2025-02-06T01:49:51.186Z,1738806591.186 [Startup:StartupSatComms:B] Stopped 2025-02-06T01:49:51.186Z,1738806591.186 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-02-06T01:49:51.186Z,1738806591.186 [Startup:StartupSatComms] Stopped 2025-02-06T01:49:51.186Z,1738806591.186 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-02-06T01:49:51.187Z,1738806591.187 [Startup](INFO): Completed Startup 2025-02-06T01:49:51.187Z,1738806591.187 [MissionManager](INFO): Startup is completed. 2025-02-06T01:49:51.188Z,1738806591.188 [MissionManager](INFO): Uninitializing Mission Startup 2025-02-06T01:49:51.188Z,1738806591.188 [Startup] Stopped 2025-02-06T01:49:51.188Z,1738806591.188 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-02-06T01:49:51.188Z,1738806591.188 [Startup:A.GoToSurface] Stopped 2025-02-06T01:49:51.188Z,1738806591.188 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-06T01:49:51.505Z,1738806591.505 [MissionManager](IMPORTANT): Started mission Default 2025-02-06T01:49:51.505Z,1738806591.505 [Default] Running Loop=1 2025-02-06T01:49:51.521Z,1738806591.521 [Default](DEBUG): Aggregate::initialize Default 2025-02-06T01:49:51.521Z,1738806591.521 [Default:B.GoToSurface] Running Loop=1 2025-02-06T01:49:51.521Z,1738806591.521 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-02-06T01:49:51.522Z,1738806591.522 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-02-06T01:49:51.522Z,1738806591.522 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-02-06T01:49:51.522Z,1738806591.522 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-02-06T01:49:51.522Z,1738806591.522 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-02-06T01:49:51.523Z,1738806591.523 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-02-06T01:49:51.523Z,1738806591.523 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-02-06T01:49:51.523Z,1738806591.523 [Default:A.Wait] Running Loop=1 2025-02-06T01:49:51.523Z,1738806591.523 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-02-06T01:49:59.537Z,1738806599.537 [DataOverHttps](INFO): Sending 269 bytes from file Logs/20250206T014651/Courier0000.lzma 2025-02-06T01:50:00.539Z,1738806600.539 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Courier0000.lzma.bak 2025-02-06T01:50:00.539Z,1738806600.539 [DataOverHttps](INFO): SBD MOMSN=23958413 2025-02-06T01:50:04.845Z,1738806604.845 [Default:A.Wait](INFO): Done Waiting. 2025-02-06T01:50:04.845Z,1738806604.845 [Default:A.Wait] Stopped 2025-02-06T01:50:04.845Z,1738806604.845 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T01:50:05.256Z,1738806605.256 [Default:CheckIn] Running Loop=1 2025-02-06T01:50:05.256Z,1738806605.256 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-02-06T01:50:05.256Z,1738806605.256 [Default:CheckIn:Read_GPS] Running Loop=1 2025-02-06T01:50:05.654Z,1738806605.654 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-02-06T01:50:07.264Z,1738806607.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,015003.00,A,3647.39605,N,12150.53170,W,1.050,168.50,060225,,,A*7E 2025-02-06T01:50:07.266Z,1738806607.266 [NAL9602](INFO): GPS fix at 20250206T015003: (36.789934, -121.842195) 2025-02-06T01:50:07.294Z,1738806607.294 [Default:CheckIn:Read_GPS] Stopped 2025-02-06T01:50:07.294Z,1738806607.294 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-02-06T01:50:07.703Z,1738806607.703 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-02-06T01:50:27.841Z,1738806627.841 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250206T014651/Courier0004.lzma 2025-02-06T01:50:28.844Z,1738806628.844 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Courier0004.lzma.bak 2025-02-06T01:50:28.844Z,1738806628.844 [DataOverHttps](INFO): SBD MOMSN=23958419 2025-02-06T01:50:39.355Z,1738806639.355 [NAL9602](INFO): Not Powering down - fast GPS 2025-02-06T01:50:45.637Z,1738806645.637 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250206T010046/Express0044.lzma 2025-02-06T01:50:46.639Z,1738806646.639 [DataOverHttps](INFO): Moved sent file to Logs/20250206T010046/Express0044.lzma.bak 2025-02-06T01:50:46.639Z,1738806646.639 [DataOverHttps](INFO): SBD MOMSN=23958422 2025-02-06T01:51:03.347Z,1738806663.347 [DataOverHttps](INFO): Sending 1001 bytes from file Logs/20250206T014651/Express0001.lzma 2025-02-06T01:51:04.347Z,1738806664.347 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Express0001.lzma.bak 2025-02-06T01:51:04.347Z,1738806664.347 [DataOverHttps](INFO): SBD MOMSN=23958424 2025-02-06T01:51:28.801Z,1738806688.801 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20250206T014651/Express0005.lzma 2025-02-06T01:51:29.803Z,1738806689.803 [DataOverHttps](INFO): Moved sent file to Logs/20250206T014651/Express0005.lzma.bak 2025-02-06T01:51:29.803Z,1738806689.803 [DataOverHttps](INFO): SBD MOMSN=23958440 2025-02-06T01:51:31.140Z,1738806691.140 [Default:CheckIn:Read_Iridium] Stopped 2025-02-06T01:51:31.140Z,1738806691.140 [Default:CheckIn:C.Wait] Running Loop=1 2025-02-06T01:51:31.140Z,1738806691.140 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-02-06T01:52:49.235Z,1738806769.235 [CommandExec](IMPORTANT): got command restart application 2025-02-06T01:52:50.241Z,1738806770.241 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-02-06T01:52:50.242Z,1738806770.242 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:50.242Z,1738806770.242 [CommandExec](INFO): Uninitializing the command executive. 2025-02-06T01:52:50.242Z,1738806770.242 [CommandExec](INFO): Uninitializing the command scheduler. 2025-02-06T01:52:50.242Z,1738806770.242 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.290Z,1738806770.290 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-02-06T01:52:50.290Z,1738806770.290 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-02-06T01:52:50.291Z,1738806770.291 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.291Z,1738806770.291 [NavChartDb](INFO): Join timeout helper Thread ID is 1054 2025-02-06T01:52:50.489Z,1738806770.489 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:50.490Z,1738806770.490 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.509Z,1738806770.509 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-02-06T01:52:50.509Z,1738806770.509 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.517Z,1738806770.517 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1055 2025-02-06T01:52:50.794Z,1738806770.794 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:50.794Z,1738806770.794 [WetLabsBB2FL](INFO): Powering down 2025-02-06T01:52:50.795Z,1738806770.795 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.798Z,1738806770.798 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-02-06T01:52:50.798Z,1738806770.798 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:50.798Z,1738806770.798 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1056 2025-02-06T01:52:51.297Z,1738806771.297 [CTD_Seabird](INFO): Powering down 2025-02-06T01:52:51.309Z,1738806771.309 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:51.309Z,1738806771.309 [CTD_Seabird](INFO): Powering down 2025-02-06T01:52:51.321Z,1738806771.321 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:51.333Z,1738806771.333 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-02-06T01:52:51.333Z,1738806771.333 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:51.334Z,1738806771.334 [Radio_Surface](INFO): Join timeout helper Thread ID is 1057 2025-02-06T01:52:51.605Z,1738806771.605 [Radio_Surface](INFO): Powering down 2025-02-06T01:52:51.606Z,1738806771.606 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:51.607Z,1738806771.607 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:51.616Z,1738806771.616 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-02-06T01:52:51.616Z,1738806771.616 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:51.616Z,1738806771.616 [Onboard](INFO): Join timeout helper Thread ID is 1058 2025-02-06T01:52:52.114Z,1738806772.114 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-02-06T01:52:53.101Z,1738806773.101 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:53.102Z,1738806773.102 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:53.129Z,1738806773.129 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-02-06T01:52:53.129Z,1738806773.129 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:53.129Z,1738806773.129 [DataOverHttps](INFO): Join timeout helper Thread ID is 1059 2025-02-06T01:52:54.121Z,1738806774.121 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.122Z,1738806774.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.134Z,1738806774.134 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-02-06T01:52:54.134Z,1738806774.134 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.135Z,1738806774.135 [DAT](INFO): Join timeout helper Thread ID is 1060 2025-02-06T01:52:54.358Z,1738806774.358 [DAT](INFO): Powering down 2025-02-06T01:52:54.430Z,1738806774.430 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.430Z,1738806774.430 [DAT](INFO): Powering down 2025-02-06T01:52:54.431Z,1738806774.431 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.446Z,1738806774.446 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-02-06T01:52:54.446Z,1738806774.446 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.446Z,1738806774.446 [BackseatComponent](INFO): Join timeout helper Thread ID is 1061 2025-02-06T01:52:54.513Z,1738806774.513 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.514Z,1738806774.514 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.526Z,1738806774.526 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-02-06T01:52:54.526Z,1738806774.526 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.526Z,1738806774.526 [logger](INFO): Join timeout helper Thread ID is 1062 2025-02-06T01:52:54.529Z,1738806774.529 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.530Z,1738806774.530 [logger ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.546Z,1738806774.546 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-02-06T01:52:54.546Z,1738806774.546 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.546Z,1738806774.546 [CommandLine](INFO): Join timeout helper Thread ID is 1063 2025-02-06T01:52:54.553Z,1738806774.553 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.554Z,1738806774.554 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.566Z,1738806774.566 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-02-06T01:52:54.566Z,1738806774.566 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.566Z,1738806774.566 [CommandExec](INFO): Join timeout helper Thread ID is 1064 2025-02-06T01:52:54.568Z,1738806774.568 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-02-06T01:52:54.568Z,1738806774.568 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.568Z,1738806774.568 [controlThread](INFO): Join timeout helper Thread ID is 1065 2025-02-06T01:52:54.729Z,1738806774.729 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-02-06T01:52:54.730Z,1738806774.730 [controlThread](DEBUG): Uninitializing ControlThread 2025-02-06T01:52:54.730Z,1738806774.730 [AHRS_M2](INFO): Powering down 2025-02-06T01:52:54.803Z,1738806774.803 [NAL9602](INFO): Powering down 2025-02-06T01:52:54.804Z,1738806774.804 [Sonardyne_Nano](INFO): Powering down 2025-02-06T01:52:54.922Z,1738806774.922 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-02-06T01:52:54.923Z,1738806774.923 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-02-06T01:52:54.923Z,1738806774.923 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-02-06T01:52:54.924Z,1738806774.924 [MissionManager](INFO): Uninitializing Mission Default 2025-02-06T01:52:54.924Z,1738806774.924 [Default] Stopped 2025-02-06T01:52:54.924Z,1738806774.924 [Default](DEBUG): Aggregate::uninitialize Default 2025-02-06T01:52:54.924Z,1738806774.924 [Default:B.GoToSurface] Stopped 2025-02-06T01:52:54.924Z,1738806774.924 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-02-06T01:52:54.924Z,1738806774.924 [Default:CheckIn] Stopped 2025-02-06T01:52:54.924Z,1738806774.924 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-02-06T01:52:54.924Z,1738806774.924 [Default:CheckIn:C.Wait] Stopped 2025-02-06T01:52:54.924Z,1738806774.924 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-02-06T01:52:54.927Z,1738806774.927 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-02-06T01:52:54.928Z,1738806774.928 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-02-06T01:52:54.928Z,1738806774.928 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-02-06T01:52:54.928Z,1738806774.928 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-02-06T01:52:54.928Z,1738806774.928 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-02-06T01:52:54.929Z,1738806774.929 [BuoyancyServo](INFO): Powering down 2025-02-06T01:52:54.941Z,1738806774.941 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-02-06T01:52:54.941Z,1738806774.941 [ElevatorServo](INFO): Powering down 2025-02-06T01:52:54.942Z,1738806774.942 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-02-06T01:52:54.942Z,1738806774.942 [MassServo](INFO): Powering down 2025-02-06T01:52:54.943Z,1738806774.943 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-02-06T01:52:54.943Z,1738806774.943 [RudderServo](INFO): Powering down 2025-02-06T01:52:54.944Z,1738806774.944 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-02-06T01:52:54.944Z,1738806774.944 [ThrusterHE](INFO): Powering down 2025-02-06T01:52:54.945Z,1738806774.945 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-02-06T01:52:54.946Z,1738806774.946 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-02-06T01:52:54.946Z,1738806774.946 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-02-06T01:52:54.946Z,1738806774.946 [CBIT](DEBUG): Powering off loads. 2025-02-06T01:52:54.957Z,1738806774.957 [CBIT](DEBUG): Disabling WDT. 2025-02-06T01:52:54.969Z,1738806774.969 [CBIT](DEBUG): Opening all GF detection circuits. 2025-02-06T01:52:54.970Z,1738806774.970 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:54.000Z,1738806775.000 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.003Z,1738806775.003 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.009Z,1738806775.009 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.016Z,1738806775.016 [DAT ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.058Z,1738806775.058 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.143Z,1738806775.143 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.148Z,1738806775.148 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.166Z,1738806775.166 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-02-06T01:52:55.240Z,1738806775.240 [logger ThreadHandler](INFO): Thread cancelled.