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.