2024-04-30T16:39:20.811Z,1714495160.811 [Supervisor](DEBUG): Initializing supervisor. 2024-04-30T16:39:20.816Z,1714495160.816 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-04-30T16:39:20.817Z,1714495160.817 [SyncHandler](INFO): Protected caller Thread ID is 994 2024-04-30T16:39:20.817Z,1714495160.817 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-04-30T16:39:20.818Z,1714495160.818 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-04-30T16:39:20.819Z,1714495160.819 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 995 2024-04-30T16:39:20.823Z,1714495160.823 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-04-30T16:39:20.841Z,1714495160.841 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-04-30T16:39:20.842Z,1714495160.842 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-04-30T16:39:20.842Z,1714495160.842 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 996 2024-04-30T16:39:20.846Z,1714495160.846 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-04-30T16:39:20.847Z,1714495160.847 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-04-30T16:39:20.848Z,1714495160.848 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 997 2024-04-30T16:39:20.850Z,1714495160.850 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-04-30T16:39:20.851Z,1714495160.851 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-04-30T16:39:20.851Z,1714495160.851 [logger ThreadHandler](INFO): Protected caller Thread ID is 998 2024-04-30T16:39:20.855Z,1714495160.855 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-04-30T16:39:20.855Z,1714495160.855 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-04-30T16:39:20.857Z,1714495160.857 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-04-30T16:39:21.081Z,1714495161.081 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-04-30T16:39:21.082Z,1714495161.082 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-04-30T16:39:21.202Z,1714495161.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-04-30T16:39:21.202Z,1714495161.202 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-04-30T16:39:21.308Z,1714495161.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-04-30T16:39:21.309Z,1714495161.309 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-04-30T16:39:21.829Z,1714495161.829 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-04-30T16:39:21.830Z,1714495161.830 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-04-30T16:39:22.198Z,1714495162.198 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-04-30T16:39:22.199Z,1714495162.199 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-04-30T16:39:22.557Z,1714495162.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-04-30T16:39:22.558Z,1714495162.558 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-04-30T16:39:22.763Z,1714495162.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-04-30T16:39:22.764Z,1714495162.764 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-04-30T16:39:22.853Z,1714495162.853 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-04-30T16:39:22.944Z,1714495162.944 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-04-30T16:39:23.371Z,1714495163.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-04-30T16:39:23.372Z,1714495163.372 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-04-30T16:39:23.678Z,1714495163.678 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-04-30T16:39:23.679Z,1714495163.679 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-04-30T16:39:24.264Z,1714495164.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-04-30T16:39:24.265Z,1714495164.265 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-04-30T16:39:24.502Z,1714495164.502 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-04-30T16:39:24.503Z,1714495164.503 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-04-30T16:39:24.810Z,1714495164.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-04-30T16:39:24.811Z,1714495164.811 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-04-30T16:39:25.066Z,1714495165.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-04-30T16:39:25.067Z,1714495165.067 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-04-30T16:39:26.266Z,1714495166.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-04-30T16:39:26.268Z,1714495166.268 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/ 2024-04-30T16:39:26.269Z,1714495166.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/secure.cfg 2024-04-30T16:39:26.363Z,1714495166.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/vehicle.cfg 2024-04-30T16:39:26.500Z,1714495166.500 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Battery.cfg 2024-04-30T16:39:26.807Z,1714495166.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-04-30T16:39:26.808Z,1714495166.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Science.cfg 2024-04-30T16:39:26.942Z,1714495166.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Control.cfg 2024-04-30T16:39:27.052Z,1714495167.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/logger.cfg 2024-04-30T16:39:27.156Z,1714495167.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Servo.cfg 2024-04-30T16:39:27.275Z,1714495167.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Navigation.cfg 2024-04-30T16:39:27.380Z,1714495167.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Simulator.cfg 2024-04-30T16:39:27.476Z,1714495167.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/BIT.cfg 2024-04-30T16:39:27.576Z,1714495167.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-galene/Sensor.cfg 2024-04-30T16:39:27.755Z,1714495167.755 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-galene/root/ 2024-04-30T16:39:27.755Z,1714495167.755 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-04-30T16:39:27.760Z,1714495167.760 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-04-30T16:39:27.812Z,1714495167.812 [DepthRateCalculator] Loaded 2024-04-30T16:39:27.812Z,1714495167.812 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-04-30T16:39:27.818Z,1714495167.818 [PitchRateCalculator] Loaded 2024-04-30T16:39:27.818Z,1714495167.818 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-04-30T16:39:27.828Z,1714495167.828 [SpeedCalculator] Loaded 2024-04-30T16:39:27.828Z,1714495167.828 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-04-30T16:39:27.833Z,1714495167.833 [YawRateCalculator] Loaded 2024-04-30T16:39:27.833Z,1714495167.833 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-04-30T16:39:27.855Z,1714495167.855 [ElevatorOffsetCalculator] Loaded 2024-04-30T16:39:27.855Z,1714495167.855 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-04-30T16:39:27.856Z,1714495167.856 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-04-30T16:39:27.856Z,1714495167.856 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-04-30T16:39:27.883Z,1714495167.883 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-04-30T16:39:27.884Z,1714495167.884 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-04-30T16:39:27.893Z,1714495167.893 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-04-30T16:39:27.894Z,1714495167.894 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-04-30T16:39:28.353Z,1714495168.353 [AHRS_M2] Loaded 2024-04-30T16:39:28.353Z,1714495168.353 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-04-30T16:39:28.388Z,1714495168.388 [BackseatComponent] Loaded 2024-04-30T16:39:28.388Z,1714495168.388 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-04-30T16:39:28.389Z,1714495168.389 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408584E0 2024-04-30T16:39:28.389Z,1714495168.389 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1080 2024-04-30T16:39:28.392Z,1714495168.392 [LcmUniversalReporter] Loaded 2024-04-30T16:39:28.392Z,1714495168.392 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-04-30T16:39:29.182Z,1714495169.182 [BPC1] Loaded 2024-04-30T16:39:29.182Z,1714495169.182 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-04-30T16:39:29.254Z,1714495169.254 [DataOverHttps] Loaded 2024-04-30T16:39:29.255Z,1714495169.255 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-04-30T16:39:29.256Z,1714495169.256 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408884E0 2024-04-30T16:39:29.256Z,1714495169.256 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1081 2024-04-30T16:39:29.276Z,1714495169.276 [Depth_Keller] Loaded 2024-04-30T16:39:29.277Z,1714495169.277 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-04-30T16:39:29.281Z,1714495169.281 [DropWeight] Loaded 2024-04-30T16:39:29.282Z,1714495169.282 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-04-30T16:39:29.300Z,1714495169.300 [MultiRay] Loaded 2024-04-30T16:39:29.300Z,1714495169.300 [ComponentRegistry](DEBUG): SyncComponent "MultiRay" handled in the control thread. 2024-04-30T16:39:29.362Z,1714495169.362 [NAL9602] Loaded 2024-04-30T16:39:29.362Z,1714495169.362 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-04-30T16:39:29.391Z,1714495169.391 [Onboard] Loaded 2024-04-30T16:39:29.391Z,1714495169.391 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-04-30T16:39:29.392Z,1714495169.392 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B84E0 2024-04-30T16:39:29.393Z,1714495169.393 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1082 2024-04-30T16:39:29.399Z,1714495169.399 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2024-04-30T16:39:29.410Z,1714495169.410 [PowerOnly] Loaded 2024-04-30T16:39:29.410Z,1714495169.410 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-04-30T16:39:29.422Z,1714495169.422 [Power24vConverter] Loaded 2024-04-30T16:39:29.422Z,1714495169.422 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-04-30T16:39:29.436Z,1714495169.436 [Radio_Surface] Loaded 2024-04-30T16:39:29.437Z,1714495169.437 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-04-30T16:39:29.438Z,1714495169.438 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E84E0 2024-04-30T16:39:29.438Z,1714495169.438 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1083 2024-04-30T16:39:29.528Z,1714495169.528 [DAT] Loaded 2024-04-30T16:39:29.529Z,1714495169.529 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-04-30T16:39:29.530Z,1714495169.530 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409184E0 2024-04-30T16:39:29.530Z,1714495169.530 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1084 2024-04-30T16:39:29.530Z,1714495169.530 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-04-30T16:39:29.531Z,1714495169.531 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-04-30T16:39:29.641Z,1714495169.641 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-04-30T16:39:29.641Z,1714495169.641 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-04-30T16:39:29.683Z,1714495169.683 [DeadReckonUsingSpeedCalculator] Loaded 2024-04-30T16:39:29.683Z,1714495169.683 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-04-30T16:39:29.697Z,1714495169.697 [NavChart] Loaded 2024-04-30T16:39:29.697Z,1714495169.697 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-04-30T16:39:29.703Z,1714495169.703 [UniversalFixResidualReporter] Loaded 2024-04-30T16:39:29.703Z,1714495169.703 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-04-30T16:39:29.704Z,1714495169.704 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-04-30T16:39:29.704Z,1714495169.704 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-04-30T16:39:29.922Z,1714495169.922 [SBIT](DEBUG): Construct Startup Built In Test. 2024-04-30T16:39:29.936Z,1714495169.936 [SBIT] Loaded 2024-04-30T16:39:29.936Z,1714495169.936 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-04-30T16:39:29.939Z,1714495169.939 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-04-30T16:39:29.952Z,1714495169.952 [IBIT] Loaded 2024-04-30T16:39:29.952Z,1714495169.952 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-04-30T16:39:29.958Z,1714495169.958 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-04-30T16:39:30.234Z,1714495170.234 [CBIT] Loaded 2024-04-30T16:39:30.234Z,1714495170.234 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-04-30T16:39:30.234Z,1714495170.234 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-04-30T16:39:30.235Z,1714495170.235 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-04-30T16:39:30.408Z,1714495170.408 [CTD_Seabird] Loaded 2024-04-30T16:39:30.409Z,1714495170.409 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-04-30T16:39:30.410Z,1714495170.410 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0 2024-04-30T16:39:30.410Z,1714495170.410 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1085 2024-04-30T16:39:30.430Z,1714495170.430 [PAR_Licor] Loaded 2024-04-30T16:39:30.430Z,1714495170.430 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-04-30T16:39:30.475Z,1714495170.475 [WetLabsBB2FL] Loaded 2024-04-30T16:39:30.475Z,1714495170.475 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-04-30T16:39:30.476Z,1714495170.476 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A504E0 2024-04-30T16:39:30.477Z,1714495170.477 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1086 2024-04-30T16:39:30.477Z,1714495170.477 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-04-30T16:39:30.478Z,1714495170.478 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-04-30T16:39:30.613Z,1714495170.613 [BuoyancyServo] Loaded 2024-04-30T16:39:30.613Z,1714495170.613 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-04-30T16:39:30.634Z,1714495170.634 [ElevatorServo] Loaded 2024-04-30T16:39:30.635Z,1714495170.635 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-04-30T16:39:30.656Z,1714495170.656 [MassServo] Loaded 2024-04-30T16:39:30.656Z,1714495170.656 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-04-30T16:39:30.676Z,1714495170.676 [RudderServo] Loaded 2024-04-30T16:39:30.677Z,1714495170.677 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-04-30T16:39:30.692Z,1714495170.692 [ThrusterHE] Loaded 2024-04-30T16:39:30.693Z,1714495170.693 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-04-30T16:39:30.693Z,1714495170.693 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-04-30T16:39:30.693Z,1714495170.693 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-04-30T16:39:30.747Z,1714495170.747 [VerticalControl](DEBUG): Construct VerticalControl. 2024-04-30T16:39:30.799Z,1714495170.799 [VerticalControl] Loaded 2024-04-30T16:39:30.800Z,1714495170.800 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-04-30T16:39:30.803Z,1714495170.803 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-04-30T16:39:30.847Z,1714495170.847 [HorizontalControl] Loaded 2024-04-30T16:39:30.847Z,1714495170.847 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-04-30T16:39:30.850Z,1714495170.850 [SpeedControl](DEBUG): Construct SpeedControl. 2024-04-30T16:39:30.852Z,1714495170.852 [SpeedControl] Loaded 2024-04-30T16:39:30.852Z,1714495170.852 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-04-30T16:39:30.855Z,1714495170.855 [LoopControl](DEBUG): Construct LoopControl. 2024-04-30T16:39:30.855Z,1714495170.855 [LoopControl] Loaded 2024-04-30T16:39:30.856Z,1714495170.856 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-04-30T16:39:30.856Z,1714495170.856 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-04-30T16:39:30.856Z,1714495170.856 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-04-30T16:39:30.972Z,1714495170.972 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-04-30T16:39:30.972Z,1714495170.972 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-04-30T16:39:31.149Z,1714495171.149 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-04-30T16:39:31.150Z,1714495171.150 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-04-30T16:39:31.492Z,1714495171.492 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-04-30T16:39:31.492Z,1714495171.492 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-04-30T16:39:31.549Z,1714495171.549 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-04-30T16:39:31.556Z,1714495171.556 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-04-30T16:39:31.558Z,1714495171.558 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-04-30T16:39:31.570Z,1714495171.570 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-04-30T16:39:31.571Z,1714495171.571 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-04-30T16:39:31.571Z,1714495171.571 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1087 2024-04-30T16:39:31.585Z,1714495171.585 [Supervisor](INFO): Main Thread ID is 830 2024-04-30T16:39:31.585Z,1714495171.585 [Supervisor](DEBUG): Running supervisor. 2024-04-30T16:39:31.585Z,1714495171.585 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1088 2024-04-30T16:39:31.586Z,1714495171.586 [CommandExec](INFO): Initializing the command executive. 2024-04-30T16:39:31.588Z,1714495171.588 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1089 2024-04-30T16:39:31.619Z,1714495171.619 [controlThread ThreadHandler](INFO): Handler Thread ID is 1090 2024-04-30T16:39:31.620Z,1714495171.620 [controlThread](DEBUG): Initializing ControlThread 2024-04-30T16:39:31.621Z,1714495171.621 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-04-30T16:39:31.621Z,1714495171.621 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-04-30T16:39:31.621Z,1714495171.621 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-04-30T16:39:31.621Z,1714495171.621 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-04-30T16:39:31.622Z,1714495171.622 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-04-30T16:39:31.630Z,1714495171.630 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:39:31.631Z,1714495171.631 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-04-30T16:39:31.631Z,1714495171.631 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-04-30T16:39:31.632Z,1714495171.632 [SBIT](INFO): Initialize SBIT Component. 2024-04-30T16:39:31.632Z,1714495171.632 [SBIT](IMPORTANT): git: 2024-04-26_A 2024-04-30T16:39:31.632Z,1714495171.632 [SBIT](INFO): git hash: 26b86b0032c96ca2fff2505cfdfca7953dc64339 2024-04-30T16:39:31.633Z,1714495171.633 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-04-30T16:39:31.634Z,1714495171.634 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-04-30T16:39:31.635Z,1714495171.635 [SBIT](INFO): Beginning SBIT in 84.000000 seconds. 2024-04-30T16:39:31.636Z,1714495171.636 [IBIT](INFO): Initialize IBIT Component. 2024-04-30T16:39:31.636Z,1714495171.636 [CBIT](DEBUG): Initialize CBIT Component. 2024-04-30T16:39:31.637Z,1714495171.637 [logger ThreadHandler](INFO): Handler Thread ID is 1091 2024-04-30T16:39:31.638Z,1714495171.638 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1092 2024-04-30T16:39:31.640Z,1714495171.640 [Onboard ThreadHandler](INFO): Handler Thread ID is 1094 2024-04-30T16:39:31.651Z,1714495171.651 [CBIT](DEBUG): Initialized mux pins. 2024-04-30T16:39:31.651Z,1714495171.651 [CBIT](DEBUG): Initializing the watchdog timer. 2024-04-30T16:39:31.671Z,1714495171.671 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1095 2024-04-30T16:39:31.675Z,1714495171.675 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-04-30T16:39:31.676Z,1714495171.676 [CBIT](DEBUG): Initializing heartbeat. 2024-04-30T16:39:31.691Z,1714495171.691 [DAT ThreadHandler](INFO): Handler Thread ID is 1096 2024-04-30T16:39:31.691Z,1714495171.691 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1093 2024-04-30T16:39:31.694Z,1714495171.694 [DAT](INFO): Powering up 2024-04-30T16:39:31.694Z,1714495171.694 [DAT](DEBUG): Initializing DAT. 2024-04-30T16:39:31.705Z,1714495171.705 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-04-30T16:39:31.708Z,1714495171.708 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1098 2024-04-30T16:39:31.708Z,1714495171.708 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-04-30T16:39:31.710Z,1714495171.710 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1100 2024-04-30T16:39:31.733Z,1714495171.733 [WetLabsBB2FL](INFO): Powering up 2024-04-30T16:39:31.734Z,1714495171.734 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1102 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-04-30T16:39:31.737Z,1714495171.737 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-04-30T16:39:31.738Z,1714495171.738 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-04-30T16:39:31.738Z,1714495171.738 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-04-30T16:39:31.747Z,1714495171.747 [CBIT](DEBUG): Deactivating GF circuits. 2024-04-30T16:39:31.747Z,1714495171.747 [CBIT](DEBUG): Deactivating emergency mode. 2024-04-30T16:39:31.787Z,1714495171.787 [CBIT](DEBUG): Backplane powered. 2024-04-30T16:39:31.793Z,1714495171.793 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-04-30T16:39:31.795Z,1714495171.795 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-04-30T16:39:31.796Z,1714495171.796 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-04-30T16:39:31.796Z,1714495171.796 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-04-30T16:39:31.797Z,1714495171.797 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-04-30T16:39:31.798Z,1714495171.798 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-04-30T16:39:31.798Z,1714495171.798 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-04-30T16:39:31.799Z,1714495171.799 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-04-30T16:39:31.845Z,1714495171.845 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-04-30T16:39:31.854Z,1714495171.854 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-30T16:39:31.878Z,1714495171.878 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-04-30T16:39:31.883Z,1714495171.883 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-04-30T16:39:31.883Z,1714495171.883 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-04-30T16:39:31.884Z,1714495171.884 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-04-30T16:39:32.075Z,1714495172.075 [Radio_Surface](INFO): Powering up 2024-04-30T16:39:32.114Z,1714495172.114 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-04-30T16:39:32.135Z,1714495172.135 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-04-30T16:39:32.137Z,1714495172.137 [Default:A.Wait](DEBUG): Construct Wait. 2024-04-30T16:39:32.148Z,1714495172.148 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-30T16:39:32.180Z,1714495172.180 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-04-30T16:39:32.187Z,1714495172.187 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-04-30T16:39:32.202Z,1714495172.202 [Default:E.Execute](DEBUG): Construct Execute. 2024-04-30T16:39:32.205Z,1714495172.205 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-04-30T16:39:32.211Z,1714495172.211 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,MultiRay,NAL9602,PowerOnly,Power24vConverter,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-04-30T16:39:32.230Z,1714495172.230 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-04-30T16:39:32.285Z,1714495172.285 [MultiRay](INFO): Powering up MultiRay Lights 2024-04-30T16:39:32.324Z,1714495172.324 [PowerOnly](INFO): Powering up loadControl 2024-04-30T16:39:32.344Z,1714495172.344 [Power24vConverter](INFO): Powering up. 2024-04-30T16:39:32.381Z,1714495172.381 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:39:32.388Z,1714495172.388 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-04-30T16:39:32.388Z,1714495172.388 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:39:32.395Z,1714495172.395 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-04-30T16:39:32.396Z,1714495172.396 [MassServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:39:32.409Z,1714495172.409 [MassServo](DEBUG): Initializing MassServo. 2024-04-30T16:39:32.410Z,1714495172.410 [RudderServo](DEBUG): Initializing EZServoServo. 2024-04-30T16:39:32.416Z,1714495172.416 [RudderServo](DEBUG): Initializing RudderServo. 2024-04-30T16:39:32.417Z,1714495172.417 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-04-30T16:39:32.423Z,1714495172.423 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-04-30T16:39:34.351Z,1714495174.351 [WetLabsBB2FL](INFO): Powering down 2024-04-30T16:39:38.148Z,1714495178.148 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-04-30T16:39:39.749Z,1714495179.749 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-04-30T16:39:44.052Z,1714495184.052 [DAT](INFO): DAT read: 2024-04-30T16:39:44.053Z,1714495184.053 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-04-30T16:39:45.816Z,1714495185.816 [DAT](INFO): DAT read: MF Frequency Band 2024-04-30T16:39:45.817Z,1714495185.817 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-04-30T16:39:45.818Z,1714495185.818 [DAT](INFO): DAT read: Apr 30 2024 16:39:38 2024-04-30T16:39:46.824Z,1714495186.824 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-04-30T16:39:46.825Z,1714495186.825 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-04-30T16:39:46.826Z,1714495186.826 [DAT](INFO): commRate: 800 2024-04-30T16:39:48.895Z,1714495188.895 [DAT](INFO): entering command mode 2024-04-30T16:39:49.092Z,1714495189.092 [DAT](INFO): DAT read: 2024-04-30T16:39:49.092Z,1714495189.092 [DAT](INFO): DAT read: user:1> 2024-04-30T16:39:49.093Z,1714495189.093 [DAT](INFO): setting verbose to 3 2024-04-30T16:39:49.344Z,1714495189.344 [DAT](INFO): DAT read: user:1> 2024-04-30T16:39:49.345Z,1714495189.345 [DAT](INFO): DAT read: Verbose | 3 2024-04-30T16:39:49.345Z,1714495189.345 [DAT](INFO): set verbose to 3 2024-04-30T16:39:49.345Z,1714495189.345 [DAT](INFO): setting DatVerbose to 27440 2024-04-30T16:39:49.596Z,1714495189.596 [DAT](INFO): DAT read: user:2> 2024-04-30T16:39:49.597Z,1714495189.597 [DAT](INFO): DAT read: DatVerbose | 27440 2024-04-30T16:39:49.597Z,1714495189.597 [DAT](INFO): set DatVerbose to 27440 2024-04-30T16:39:49.597Z,1714495189.597 [DAT](INFO): setting transmit power to 8 2024-04-30T16:39:49.848Z,1714495189.848 [DAT](INFO): DAT read: user:3> 2024-04-30T16:39:49.849Z,1714495189.849 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-04-30T16:39:49.849Z,1714495189.849 [DAT](INFO): set transmit power to 8 2024-04-30T16:39:49.849Z,1714495189.849 [DAT](INFO): setting local address to 9 2024-04-30T16:39:50.100Z,1714495190.100 [DAT](INFO): DAT read: user:4> 2024-04-30T16:39:50.101Z,1714495190.101 [DAT](INFO): DAT read: LocalAddr | 9 2024-04-30T16:39:50.101Z,1714495190.101 [DAT](INFO): set local address to 9 2024-04-30T16:39:50.102Z,1714495190.102 [DAT](INFO): Setting time to: 16:39:50 And date to:4/30/2024 2024-04-30T16:39:50.352Z,1714495190.352 [DAT](INFO): DAT read: user:5> 2024-04-30T16:39:50.353Z,1714495190.353 [DAT](INFO): DAT read: Tue Apr 30, 2024 16:39:50 2024-04-30T16:39:50.353Z,1714495190.353 [DAT](INFO): Local DAT time set to Tue Apr 30, 2024 16:39:50 2024-04-30T16:39:59.757Z,1714495199.757 [NAL9602](INFO): Powering up NAL9602 2024-04-30T16:40:10.665Z,1714495210.665 [NAL9602](INFO): NAL9602 initialized 2024-04-30T16:40:27.240Z,1714495227.240 [CommandExec](IMPORTANT): got command strobe off 2024-04-30T16:40:27.241Z,1714495227.241 [CommandExec](IMPORTANT): Deactivating strobe 2024-04-30T16:40:56.351Z,1714495256.351 [SBIT](IMPORTANT): Beginning Startup BIT 2024-04-30T16:40:56.356Z,1714495256.356 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-30T16:41:07.755Z,1714495267.755 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004825 CHAN A1 (24V): 0.000342 CHAN A2 (12V): -0.005837 CHAN A3 (5V): -0.002875 CHAN B0 (3.3V): -0.001298 CHAN B1 (3.15aV): -0.000923 CHAN B2 (3.15bV): -0.001059 CHAN B3 (GND): -0.000572 OPEN: 0.004956 Full Scale: +/- 1 mA 2024-04-30T16:41:50.067Z,1714495310.067 [SBIT](IMPORTANT): SBIT PASSED 2024-04-30T16:41:50.068Z,1714495310.068 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-04-30T16:41:50.069Z,1714495310.069 [SBIT](IMPORTANT): MultiRay.loadAtStartup=1 bool; 2024-04-30T16:41:50.069Z,1714495310.069 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=245.745130 cubic_centimeter; 2024-04-30T16:41:50.069Z,1714495310.069 [SBIT](IMPORTANT): VerticalControl.massDefault=16.093918 millimeter; 2024-04-30T16:41:50.473Z,1714495310.473 [MissionManager](IMPORTANT): Started mission Startup 2024-04-30T16:41:50.473Z,1714495310.473 [Startup] Running Loop=1 2024-04-30T16:41:50.473Z,1714495310.473 [Startup](DEBUG): Aggregate::initialize Startup 2024-04-30T16:41:50.473Z,1714495310.473 [Startup:A.GoToSurface] Running Loop=1 2024-04-30T16:41:50.474Z,1714495310.474 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-30T16:41:50.474Z,1714495310.474 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-30T16:41:50.475Z,1714495310.475 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-30T16:41:50.475Z,1714495310.475 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-30T16:41:50.476Z,1714495310.476 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-30T16:41:50.476Z,1714495310.476 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-30T16:41:50.477Z,1714495310.477 [Startup:StartupSatComms] Running Loop=1 2024-04-30T16:41:50.478Z,1714495310.478 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-04-30T16:41:50.478Z,1714495310.478 [Startup:StartupSatComms:A] Running Loop=1 2024-04-30T16:41:50.870Z,1714495310.870 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-04-30T16:41:59.570Z,1714495319.570 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005516 2024-04-30T16:42:31.691Z,1714495351.691 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-04-30T16:42:31.691Z,1714495351.691 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:42:31.692Z,1714495351.692 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-04-30T16:42:31.692Z,1714495351.692 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:42:31.712Z,1714495351.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:42:31.712Z,1714495351.712 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:42:32.118Z,1714495352.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:42:32.118Z,1714495352.118 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-04-30T16:42:32.123Z,1714495352.123 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:42:32.123Z,1714495352.123 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-04-30T16:42:32.487Z,1714495352.487 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:42:50.666Z,1714495370.666 [Startup:StartupSatComms:A](INFO): Timed out from 2024-04-30T16:41:50.5Z 2024-04-30T16:42:50.666Z,1714495370.666 [Startup:StartupSatComms:A] Stopped 2024-04-30T16:42:50.666Z,1714495370.666 [Startup:StartupSatComms:B] Running Loop=1 2024-04-30T16:42:51.090Z,1714495371.090 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-30T16:42:57.527Z,1714495377.527 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240430T162249/Courier0007.lzma 2024-04-30T16:42:58.529Z,1714495378.529 [DataOverHttps](INFO): Moved sent file to Logs/20240430T162249/Courier0007.lzma.bak 2024-04-30T16:42:58.529Z,1714495378.529 [DataOverHttps](INFO): SBD MOMSN=19612175 2024-04-30T16:43:14.227Z,1714495394.227 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240430T163920/Courier0000.lzma 2024-04-30T16:43:15.229Z,1714495395.229 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0000.lzma.bak 2024-04-30T16:43:15.229Z,1714495395.229 [DataOverHttps](INFO): SBD MOMSN=19612177 2024-04-30T16:43:31.015Z,1714495411.015 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20240430T162249/Express0008.lzma 2024-04-30T16:43:32.017Z,1714495412.017 [DataOverHttps](INFO): Moved sent file to Logs/20240430T162249/Express0008.lzma.bak 2024-04-30T16:43:32.017Z,1714495412.017 [DataOverHttps](INFO): SBD MOMSN=19612180 2024-04-30T16:43:47.580Z,1714495427.580 [DataOverHttps](INFO): Sending 780 bytes from file Logs/20240430T163920/Express0001.lzma 2024-04-30T16:43:48.577Z,1714495428.577 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0001.lzma.bak 2024-04-30T16:43:48.577Z,1714495428.577 [DataOverHttps](INFO): SBD MOMSN=19612189 2024-04-30T16:43:49.698Z,1714495429.698 [Startup:StartupSatComms:B] Stopped 2024-04-30T16:43:49.698Z,1714495429.698 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-04-30T16:43:49.698Z,1714495429.698 [Startup:StartupSatComms] Stopped 2024-04-30T16:43:49.698Z,1714495429.698 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-04-30T16:43:49.699Z,1714495429.699 [Startup](INFO): Completed Startup 2024-04-30T16:43:49.699Z,1714495429.699 [MissionManager](INFO): Startup is completed. 2024-04-30T16:43:49.699Z,1714495429.699 [MissionManager](INFO): Uninitializing Mission Startup 2024-04-30T16:43:49.699Z,1714495429.699 [Startup] Stopped 2024-04-30T16:43:49.700Z,1714495429.700 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-04-30T16:43:49.700Z,1714495429.700 [Startup:A.GoToSurface] Stopped 2024-04-30T16:43:49.700Z,1714495429.700 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-30T16:43:50.093Z,1714495430.093 [MissionManager](IMPORTANT): Started mission Default 2024-04-30T16:43:50.093Z,1714495430.093 [Default] Running Loop=1 2024-04-30T16:43:50.093Z,1714495430.093 [Default](DEBUG): Aggregate::initialize Default 2024-04-30T16:43:50.093Z,1714495430.093 [Default:B.GoToSurface] Running Loop=1 2024-04-30T16:43:50.093Z,1714495430.093 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-30T16:43:50.094Z,1714495430.094 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-30T16:43:50.094Z,1714495430.094 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-30T16:43:50.094Z,1714495430.094 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-30T16:43:50.095Z,1714495430.095 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-30T16:43:50.096Z,1714495430.096 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-30T16:43:50.096Z,1714495430.096 [Default:A.Wait] Running Loop=1 2024-04-30T16:43:50.096Z,1714495430.096 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-30T16:44:00.844Z,1714495440.844 [CommandExec](IMPORTANT): got command failComponent 2024-04-30T16:44:00.844Z,1714495440.844 [CommandExec](IMPORTANT): Failed components: 2024-04-30T16:44:00.844Z,1714495440.844 [CommandExec](IMPORTANT): No failed Components. 2024-04-30T16:44:03.413Z,1714495443.413 [Default:A.Wait](INFO): Done Waiting. 2024-04-30T16:44:03.413Z,1714495443.413 [Default:A.Wait] Stopped 2024-04-30T16:44:03.413Z,1714495443.413 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T16:44:03.806Z,1714495443.806 [Default:CheckIn] Running Loop=1 2024-04-30T16:44:03.806Z,1714495443.806 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T16:44:03.806Z,1714495443.806 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T16:44:04.243Z,1714495444.243 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-04-30T16:45:14.521Z,1714495514.521 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-04-30T16:45:32.685Z,1714495532.685 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-04-30T16:45:32.685Z,1714495532.685 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:45:32.686Z,1714495532.686 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-04-30T16:45:32.686Z,1714495532.686 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:45:32.697Z,1714495532.697 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:45:32.698Z,1714495532.698 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:45:33.106Z,1714495533.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:45:33.106Z,1714495533.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-04-30T16:45:33.107Z,1714495533.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:45:33.107Z,1714495533.107 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-04-30T16:45:33.515Z,1714495533.515 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:47:55.289Z,1714495675.289 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2024-04-30T16:47:55.292Z,1714495675.292 [BPC1](INFO): Received data from all battery sticks. 2024-04-30T16:48:33.709Z,1714495713.709 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-04-30T16:48:33.709Z,1714495713.709 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:48:33.710Z,1714495713.710 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-04-30T16:48:33.710Z,1714495713.710 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:48:33.721Z,1714495713.721 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:48:33.721Z,1714495713.721 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:48:34.110Z,1714495714.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:48:34.110Z,1714495714.110 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-04-30T16:48:34.110Z,1714495714.110 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:48:34.110Z,1714495714.110 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-04-30T16:48:34.500Z,1714495714.500 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:49:03.989Z,1714495743.989 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-04-30T16:44:03.8Z 2024-04-30T16:49:03.989Z,1714495743.989 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T16:49:03.989Z,1714495743.989 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T16:49:04.398Z,1714495744.398 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-04-30T16:49:10.955Z,1714495750.955 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240430T163920/Courier0004.lzma 2024-04-30T16:49:11.957Z,1714495751.957 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0004.lzma.bak 2024-04-30T16:49:11.957Z,1714495751.957 [DataOverHttps](INFO): SBD MOMSN=19612222 2024-04-30T16:49:27.519Z,1714495767.519 [DataOverHttps](INFO): Sending 235 bytes from file Logs/20240430T163920/Express0005.lzma 2024-04-30T16:49:28.521Z,1714495768.521 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0005.lzma.bak 2024-04-30T16:49:28.521Z,1714495768.521 [DataOverHttps](INFO): SBD MOMSN=19612224 2024-04-30T16:49:29.870Z,1714495769.870 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T16:49:29.870Z,1714495769.870 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T16:49:29.870Z,1714495769.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T16:49:59.655Z,1714495799.655 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:50:12.665Z,1714495812.665 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-04-30T16:50:12.665Z,1714495812.665 [NAL9602] Data Fault, FailCount= 1 2024-04-30T16:50:12.665Z,1714495812.665 [NAL9602](ERROR): Data Fault 2024-04-30T16:50:12.683Z,1714495812.683 [CBIT](ERROR): Data Fault in component: NAL9602 2024-04-30T16:50:13.065Z,1714495813.065 [NAL9602](INFO): Powering down 2024-04-30T16:50:13.894Z,1714495813.894 [CBIT](INFO): Clearing failed state for component NAL9602 2024-04-30T16:50:13.894Z,1714495813.894 [NAL9602] No Fault, FailCount= 1 2024-04-30T16:50:28.788Z,1714495828.788 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.267361 2024-04-30T16:50:43.369Z,1714495843.369 [NAL9602](INFO): Powering up NAL9602 2024-04-30T16:50:54.272Z,1714495854.272 [NAL9602](INFO): NAL9602 initialized 2024-04-30T16:51:09.951Z,1714495869.951 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:51:34.704Z,1714495894.704 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-04-30T16:51:34.704Z,1714495894.704 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:51:34.705Z,1714495894.705 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2024-04-30T16:51:34.705Z,1714495894.705 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:51:34.716Z,1714495894.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:51:34.716Z,1714495894.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:51:35.118Z,1714495895.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:51:35.118Z,1714495895.118 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-04-30T16:51:35.118Z,1714495895.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:51:35.119Z,1714495895.119 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2024-04-30T16:51:35.492Z,1714495895.492 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:51:45.104Z,1714495905.104 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:52:20.243Z,1714495940.243 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:52:55.383Z,1714495975.383 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:53:30.527Z,1714496010.527 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:54:05.699Z,1714496045.699 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:54:30.433Z,1714496070.433 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T16:54:30.433Z,1714496070.433 [Default:CheckIn:C.Wait] Stopped 2024-04-30T16:54:30.433Z,1714496070.433 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T16:54:30.433Z,1714496070.433 [Default:CheckIn:D] Running Loop=1 2024-04-30T16:54:30.829Z,1714496070.829 [Default:CheckIn:D] Stopped 2024-04-30T16:54:30.829Z,1714496070.829 [Default:CheckIn:E] Running Loop=1 2024-04-30T16:54:31.246Z,1714496071.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.678935 min 2024-04-30T16:54:31.246Z,1714496071.246 [Default:CheckIn:E] Stopped 2024-04-30T16:54:31.246Z,1714496071.246 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn] Stopped 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn](INFO): Running loop #2 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn] Running Loop=2 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T16:54:31.247Z,1714496071.247 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T16:54:35.676Z,1714496075.676 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-04-30T16:54:35.676Z,1714496075.676 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:54:35.677Z,1714496075.677 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2024-04-30T16:54:35.677Z,1714496075.677 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:54:35.716Z,1714496075.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:54:35.716Z,1714496075.716 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:54:36.087Z,1714496076.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:54:36.087Z,1714496076.087 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-04-30T16:54:36.088Z,1714496076.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:54:36.088Z,1714496076.088 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2024-04-30T16:54:36.486Z,1714496076.486 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:54:40.839Z,1714496080.839 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:55:15.987Z,1714496115.987 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2024-04-30T16:55:28.296Z,1714496128.296 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003362 2024-04-30T16:57:36.671Z,1714496256.671 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-04-30T16:57:36.671Z,1714496256.671 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-04-30T16:57:36.672Z,1714496256.672 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2024-04-30T16:57:36.673Z,1714496256.673 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-04-30T16:57:36.702Z,1714496256.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-04-30T16:57:36.702Z,1714496256.702 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-04-30T16:57:37.078Z,1714496257.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-04-30T16:57:37.078Z,1714496257.078 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-04-30T16:57:37.078Z,1714496257.078 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-04-30T16:57:37.078Z,1714496257.078 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2024-04-30T16:57:37.496Z,1714496257.496 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-04-30T16:58:02.123Z,1714496282.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165800.00,A,3648.13876,N,12147.24336,W,0.078,0.00,300424,,,A*7B 2024-04-30T16:58:02.126Z,1714496282.126 [NAL9602](INFO): GPS fix at 20240430T165800: (36.802313, -121.787389) 2024-04-30T16:58:02.165Z,1714496282.165 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T16:58:02.165Z,1714496282.165 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T16:58:09.895Z,1714496289.895 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240430T163920/Courier0007.lzma 2024-04-30T16:58:10.897Z,1714496290.897 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0007.lzma.bak 2024-04-30T16:58:10.897Z,1714496290.897 [DataOverHttps](INFO): SBD MOMSN=19612320 2024-04-30T16:58:26.783Z,1714496306.783 [DataOverHttps](INFO): Sending 308 bytes from file Logs/20240430T163920/Express0008.lzma 2024-04-30T16:58:27.785Z,1714496307.785 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0008.lzma.bak 2024-04-30T16:58:27.785Z,1714496307.785 [DataOverHttps](INFO): SBD MOMSN=19612324 2024-04-30T16:58:28.806Z,1714496308.806 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T16:58:28.806Z,1714496308.806 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T16:58:28.806Z,1714496308.806 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T16:58:34.445Z,1714496314.445 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T16:59:47.385Z,1714496387.385 [DataOverHttps](IMPORTANT): SBD MTMSN=20240430T165946 2024-04-30T16:59:57.681Z,1714496397.681 [DataOverHttps](INFO): Received command: ibit 2024-04-30T16:59:57.714Z,1714496397.714 [CommandExec](IMPORTANT): got command ibit 2024-04-30T16:59:57.886Z,1714496397.886 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-04-30T16:59:57.886Z,1714496397.886 [IBIT](IMPORTANT): Beginning control surface checks. 2024-04-30T16:59:57.897Z,1714496397.897 [CBIT](IMPORTANT): Beginning ground fault scan 2024-04-30T16:59:59.333Z,1714496399.333 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165957.00,A,3648.13973,N,12147.22743,W,0.156,87.02,300424,,,A*4C 2024-04-30T16:59:59.335Z,1714496399.335 [NAL9602](INFO): GPS fix at 20240430T165957: (36.802329, -121.787124) 2024-04-30T17:00:08.702Z,1714496408.702 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.767321 CHAN A1 (24V): 0.741274 CHAN A2 (12V): 0.188158 CHAN A3 (5V): 0.033694 CHAN B0 (3.3V): 0.024341 CHAN B1 (3.15aV): 0.023426 CHAN B2 (3.15bV): 0.023611 CHAN B3 (GND): 0.000396 OPEN: 0.004856 Full Scale: +/- 1 mA 2024-04-30T17:00:12.662Z,1714496412.662 [NAL9602](INFO): SBD MO Status=0, MOMSN=16205, MT Status=0, MTMSN=0 2024-04-30T17:00:12.662Z,1714496412.662 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:00:13.872Z,1714496413.872 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170012.00,A,3648.13997,N,12147.22761,W,0.058,87.02,300424,,,A*45 2024-04-30T17:00:13.874Z,1714496413.874 [NAL9602](INFO): GPS fix at 20240430T170012: (36.802333, -121.787127) 2024-04-30T17:00:17.104Z,1714496417.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170015.00,A,3648.14012,N,12147.22764,W,0.292,87.02,300424,,,A*40 2024-04-30T17:00:17.106Z,1714496417.106 [NAL9602](INFO): GPS fix at 20240430T170015: (36.802335, -121.787127) 2024-04-30T17:00:19.930Z,1714496419.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170018.00,A,3648.14020,N,12147.22766,W,0.175,87.02,300424,,,A*44 2024-04-30T17:00:19.933Z,1714496419.933 [NAL9602](INFO): GPS fix at 20240430T170018: (36.802337, -121.787128) 2024-04-30T17:00:22.763Z,1714496422.763 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170021.00,A,3648.14021,N,12147.22763,W,0.350,87.02,300424,,,A*4F 2024-04-30T17:00:22.765Z,1714496422.765 [NAL9602](INFO): GPS fix at 20240430T170021: (36.802337, -121.787127) 2024-04-30T17:00:25.994Z,1714496425.994 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170024.00,A,3648.14017,N,12147.22748,W,0.233,87.02,300424,,,A*42 2024-04-30T17:00:25.997Z,1714496425.997 [NAL9602](INFO): GPS fix at 20240430T170024: (36.802336, -121.787125) 2024-04-30T17:00:28.822Z,1714496428.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170027.00,A,3648.14014,N,12147.22734,W,0.136,87.02,300424,,,A*4F 2024-04-30T17:00:28.824Z,1714496428.824 [NAL9602](INFO): GPS fix at 20240430T170027: (36.802336, -121.787122) 2024-04-30T17:00:31.531Z,1714496431.531 [DataOverHttps](IMPORTANT): SBD MTMSN=20240430T170030 2024-04-30T17:00:32.063Z,1714496432.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170030.00,A,3648.14008,N,12147.22714,W,0.408,87.02,300424,,,A*4E 2024-04-30T17:00:32.065Z,1714496432.065 [NAL9602](INFO): GPS fix at 20240430T170030: (36.802335, -121.787119) 2024-04-30T17:00:34.878Z,1714496434.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170033.00,A,3648.14004,N,12147.22714,W,0.117,87.02,300424,,,A*4A 2024-04-30T17:00:34.881Z,1714496434.881 [NAL9602](INFO): GPS fix at 20240430T170033: (36.802334, -121.787119) 2024-04-30T17:00:38.110Z,1714496438.110 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170036.00,A,3648.14006,N,12147.22722,W,0.505,87.02,300424,,,A*4F 2024-04-30T17:00:38.112Z,1714496438.112 [NAL9602](INFO): GPS fix at 20240430T170036: (36.802334, -121.787120) 2024-04-30T17:00:39.015Z,1714496439.015 [DataOverHttps](INFO): Received command: failc 2024-04-30T17:00:39.120Z,1714496439.120 [CommandExec](IMPORTANT): got command failComponent 2024-04-30T17:00:39.120Z,1714496439.120 [CommandExec](IMPORTANT): Failed components: 2024-04-30T17:00:39.120Z,1714496439.120 [CommandExec](IMPORTANT): No failed Components. 2024-04-30T17:00:40.941Z,1714496440.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170039.00,A,3648.14012,N,12147.22735,W,0.292,87.02,300424,,,A*4A 2024-04-30T17:00:40.943Z,1714496440.943 [NAL9602](INFO): GPS fix at 20240430T170039: (36.802335, -121.787122) 2024-04-30T17:00:43.767Z,1714496443.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170042.00,A,3648.14012,N,12147.22741,W,0.194,87.02,300424,,,A*40 2024-04-30T17:00:43.769Z,1714496443.769 [NAL9602](INFO): GPS fix at 20240430T170042: (36.802335, -121.787124) 2024-04-30T17:00:44.208Z,1714496444.208 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 4 Latitude: 36.802334 Longitude: -121.787125 2024-04-30T17:00:44.603Z,1714496444.603 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.650999 2024-04-30T17:00:44.603Z,1714496444.603 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2024-04-30T17:00:44.604Z,1714496444.604 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2024-04-30T17:00:45.023Z,1714496445.023 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-04-30T17:00:45.023Z,1714496445.023 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-04-30T17:00:45.024Z,1714496445.024 [IBIT](IMPORTANT): Pressure:9.834018 PSI 2024-04-30T17:00:45.024Z,1714496445.024 [IBIT](IMPORTANT): Humidity:8.900012 % 2024-04-30T17:00:45.398Z,1714496445.398 [IBIT](IMPORTANT): Vehicle Pitch:-2.153329 degrees 2024-04-30T17:00:45.398Z,1714496445.398 [IBIT](IMPORTANT): Vehicle Roll:2.116493 degrees 2024-04-30T17:00:45.399Z,1714496445.399 [IBIT](IMPORTANT): Vehicle Heading:20.878874 degrees 2024-04-30T17:00:45.812Z,1714496445.812 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-04-30T17:00:45.812Z,1714496445.812 [IBIT](IMPORTANT): buoyancyNeutral: 245.745117 cc 2024-04-30T17:00:45.812Z,1714496445.812 [IBIT](IMPORTANT): massDefault: 1.609392 cm 2024-04-30T17:00:45.812Z,1714496445.812 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2024-04-30T17:00:45.813Z,1714496445.813 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2024-04-30T17:00:45.813Z,1714496445.813 [IBIT](IMPORTANT): IBIT PASSED 2024-04-30T17:01:16.492Z,1714496476.492 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:03:29.464Z,1714496609.464 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:03:29.464Z,1714496609.464 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:03:29.464Z,1714496609.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:03:29.464Z,1714496609.464 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:03:29.865Z,1714496609.865 [Default:CheckIn:D] Stopped 2024-04-30T17:03:29.865Z,1714496609.865 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:03:30.273Z,1714496610.273 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 19.662856 min 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn:E] Stopped 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn] Stopped 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn](INFO): Running loop #3 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn] Running Loop=3 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:03:30.274Z,1714496610.274 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:03:32.282Z,1714496612.282 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170330.00,A,3648.13948,N,12147.22820,W,0.000,87.02,300424,,,A*43 2024-04-30T17:03:32.292Z,1714496612.292 [NAL9602](INFO): GPS fix at 20240430T170330: (36.802325, -121.787137) 2024-04-30T17:03:32.313Z,1714496612.313 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:03:32.313Z,1714496612.313 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:03:39.742Z,1714496619.742 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0010.lzma 2024-04-30T17:03:40.349Z,1714496620.349 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0010.lzma.bak 2024-04-30T17:03:40.349Z,1714496620.349 [DataOverHttps](INFO): SBD MOMSN=19612354 2024-04-30T17:03:48.900Z,1714496628.900 [NAL9602](INFO): SBD MO Status=0, MOMSN=16206, MT Status=0, MTMSN=0 2024-04-30T17:03:48.900Z,1714496628.900 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:03:57.399Z,1714496637.399 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240430T163920/Courier0013.lzma 2024-04-30T17:03:58.401Z,1714496638.401 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0013.lzma.bak 2024-04-30T17:03:58.401Z,1714496638.401 [DataOverHttps](INFO): SBD MOMSN=19612358 2024-04-30T17:04:14.356Z,1714496654.356 [DataOverHttps](INFO): Sending 857 bytes from file Logs/20240430T163920/Express0011.lzma 2024-04-30T17:04:15.357Z,1714496655.357 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0011.lzma.bak 2024-04-30T17:04:15.357Z,1714496655.357 [DataOverHttps](INFO): SBD MOMSN=19612361 2024-04-30T17:04:19.620Z,1714496659.620 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:04:31.343Z,1714496671.343 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20240430T163920/Express0014.lzma 2024-04-30T17:04:32.346Z,1714496672.346 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0014.lzma.bak 2024-04-30T17:04:32.346Z,1714496672.346 [DataOverHttps](INFO): SBD MOMSN=19612393 2024-04-30T17:04:33.382Z,1714496673.382 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:04:33.382Z,1714496673.382 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:04:33.382Z,1714496673.382 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:09:34.012Z,1714496974.012 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:09:34.012Z,1714496974.012 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:09:34.013Z,1714496974.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:09:34.013Z,1714496974.013 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:09:34.420Z,1714496974.420 [Default:CheckIn:D] Stopped 2024-04-30T17:09:34.420Z,1714496974.420 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:09:34.829Z,1714496974.829 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.738786 min 2024-04-30T17:09:34.829Z,1714496974.829 [Default:CheckIn:E] Stopped 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn] Stopped 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn](INFO): Running loop #4 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn] Running Loop=4 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:09:34.830Z,1714496974.830 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:09:36.822Z,1714496976.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170936.00,A,3648.13975,N,12147.23007,W,0.175,87.02,300424,,,A*4E 2024-04-30T17:09:36.824Z,1714496976.824 [NAL9602](INFO): GPS fix at 20240430T170936: (36.802329, -121.787168) 2024-04-30T17:09:36.873Z,1714496976.873 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:09:36.873Z,1714496976.873 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:09:45.355Z,1714496985.355 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240430T163920/Courier0016.lzma 2024-04-30T17:09:46.357Z,1714496986.357 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0016.lzma.bak 2024-04-30T17:09:46.357Z,1714496986.357 [DataOverHttps](INFO): SBD MOMSN=19612428 2024-04-30T17:10:00.682Z,1714497000.682 [NAL9602](INFO): SBD MO Status=0, MOMSN=16207, MT Status=0, MTMSN=0 2024-04-30T17:10:00.682Z,1714497000.682 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:10:02.367Z,1714497002.367 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240430T163920/Express0017.lzma 2024-04-30T17:10:03.369Z,1714497003.369 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0017.lzma.bak 2024-04-30T17:10:03.369Z,1714497003.369 [DataOverHttps](INFO): SBD MOMSN=19612431 2024-04-30T17:10:04.744Z,1714497004.744 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:10:04.744Z,1714497004.744 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:10:04.744Z,1714497004.744 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:10:31.384Z,1714497031.384 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:15:05.309Z,1714497305.309 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:15:05.309Z,1714497305.309 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:15:05.309Z,1714497305.309 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:15:05.309Z,1714497305.309 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:15:05.727Z,1714497305.727 [Default:CheckIn:D] Stopped 2024-04-30T17:15:05.727Z,1714497305.727 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.260553 min 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn:E] Stopped 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn] Stopped 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn](INFO): Running loop #5 2024-04-30T17:15:06.121Z,1714497306.121 [Default:CheckIn] Running Loop=5 2024-04-30T17:15:06.122Z,1714497306.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:15:06.122Z,1714497306.122 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:15:08.130Z,1714497308.130 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171507.00,A,3648.14005,N,12147.23035,W,0.078,87.02,300424,,,A*45 2024-04-30T17:15:08.132Z,1714497308.132 [NAL9602](INFO): GPS fix at 20240430T171507: (36.802334, -121.787172) 2024-04-30T17:15:08.145Z,1714497308.145 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:15:08.145Z,1714497308.145 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:15:15.447Z,1714497315.447 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0019.lzma 2024-04-30T17:15:16.449Z,1714497316.449 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0019.lzma.bak 2024-04-30T17:15:16.449Z,1714497316.449 [DataOverHttps](INFO): SBD MOMSN=19612524 2024-04-30T17:15:32.395Z,1714497332.395 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240430T163920/Express0020.lzma 2024-04-30T17:15:33.397Z,1714497333.397 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0020.lzma.bak 2024-04-30T17:15:33.397Z,1714497333.397 [DataOverHttps](INFO): SBD MOMSN=19612527 2024-04-30T17:15:34.810Z,1714497334.810 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:15:34.810Z,1714497334.810 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:15:34.810Z,1714497334.810 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:16:01.050Z,1714497361.050 [NAL9602](INFO): SBD MO Status=0, MOMSN=16208, MT Status=0, MTMSN=0 2024-04-30T17:16:01.050Z,1714497361.050 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:16:31.753Z,1714497391.753 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:20:35.406Z,1714497635.406 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:20:35.406Z,1714497635.406 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:20:35.406Z,1714497635.406 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:20:35.406Z,1714497635.406 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:20:35.820Z,1714497635.820 [Default:CheckIn:D] Stopped 2024-04-30T17:20:35.820Z,1714497635.820 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.762105 min 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn:E] Stopped 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn] Stopped 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn](INFO): Running loop #6 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn] Running Loop=6 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:20:36.214Z,1714497636.214 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:20:38.230Z,1714497638.230 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172037.00,A,3648.14020,N,12147.22931,W,0.292,0.00,300424,,,A*70 2024-04-30T17:20:38.232Z,1714497638.232 [NAL9602](INFO): GPS fix at 20240430T172037: (36.802337, -121.787155) 2024-04-30T17:20:38.245Z,1714497638.245 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:20:38.245Z,1714497638.245 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:20:45.427Z,1714497645.427 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0022.lzma 2024-04-30T17:20:46.429Z,1714497646.429 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0022.lzma.bak 2024-04-30T17:20:46.429Z,1714497646.429 [DataOverHttps](INFO): SBD MOMSN=19612535 2024-04-30T17:20:49.138Z,1714497649.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=16209, MT Status=0, MTMSN=0 2024-04-30T17:20:49.139Z,1714497649.139 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:21:02.407Z,1714497662.407 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240430T163920/Express0023.lzma 2024-04-30T17:21:03.409Z,1714497663.409 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0023.lzma.bak 2024-04-30T17:21:03.409Z,1714497663.409 [DataOverHttps](INFO): SBD MOMSN=19612538 2024-04-30T17:21:04.496Z,1714497664.496 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:21:04.496Z,1714497664.496 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:21:04.496Z,1714497664.496 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:21:19.842Z,1714497679.842 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:26:05.120Z,1714497965.120 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:26:05.120Z,1714497965.120 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:26:05.120Z,1714497965.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:26:05.120Z,1714497965.120 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:26:05.524Z,1714497965.524 [Default:CheckIn:D] Stopped 2024-04-30T17:26:05.524Z,1714497965.524 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:26:05.925Z,1714497965.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.257182 min 2024-04-30T17:26:05.925Z,1714497965.925 [Default:CheckIn:E] Stopped 2024-04-30T17:26:05.925Z,1714497965.925 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn] Stopped 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn](INFO): Running loop #7 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn] Running Loop=7 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:26:05.926Z,1714497965.926 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:26:07.934Z,1714497967.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172607.00,A,3648.15160,N,12147.22166,W,0.136,117.68,300424,,,A*7F 2024-04-30T17:26:07.944Z,1714497967.944 [NAL9602](INFO): GPS fix at 20240430T172607: (36.802527, -121.787028) 2024-04-30T17:26:07.957Z,1714497967.957 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:26:07.957Z,1714497967.957 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:26:15.682Z,1714497975.682 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0025.lzma 2024-04-30T17:26:16.685Z,1714497976.685 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0025.lzma.bak 2024-04-30T17:26:16.685Z,1714497976.685 [DataOverHttps](INFO): SBD MOMSN=19612542 2024-04-30T17:26:32.623Z,1714497992.623 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240430T163920/Express0026.lzma 2024-04-30T17:26:33.625Z,1714497993.625 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0026.lzma.bak 2024-04-30T17:26:33.625Z,1714497993.625 [DataOverHttps](INFO): SBD MOMSN=19612546 2024-04-30T17:26:35.003Z,1714497995.003 [NAL9602](INFO): SBD MO Status=0, MOMSN=16210, MT Status=0, MTMSN=0 2024-04-30T17:26:35.003Z,1714497995.003 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:26:35.028Z,1714497995.028 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:26:35.028Z,1714497995.028 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:26:35.028Z,1714497995.028 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:27:05.708Z,1714498025.708 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:31:35.633Z,1714498295.633 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-04-30T17:31:35.633Z,1714498295.633 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:31:35.633Z,1714498295.633 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:31:35.633Z,1714498295.633 [Default:CheckIn:D] Running Loop=1 2024-04-30T17:31:36.046Z,1714498296.046 [Default:CheckIn:D] Stopped 2024-04-30T17:31:36.046Z,1714498296.046 [Default:CheckIn:E] Running Loop=1 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.765877 min 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn:E] Stopped 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn] Stopped 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn](INFO): Running loop #8 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn] Running Loop=8 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:31:36.462Z,1714498296.462 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:31:38.454Z,1714498298.454 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173137.00,A,3648.34078,N,12147.11559,W,4.704,3.57,300424,,,A*72 2024-04-30T17:31:38.456Z,1714498298.456 [NAL9602](INFO): GPS fix at 20240430T173137: (36.805680, -121.785260) 2024-04-30T17:31:38.470Z,1714498298.470 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:31:38.470Z,1714498298.470 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:31:45.823Z,1714498305.823 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0028.lzma 2024-04-30T17:31:46.825Z,1714498306.825 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0028.lzma.bak 2024-04-30T17:31:46.825Z,1714498306.825 [DataOverHttps](INFO): SBD MOMSN=19612560 2024-04-30T17:32:05.575Z,1714498325.575 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240430T163920/Express0029.lzma 2024-04-30T17:32:06.577Z,1714498326.577 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0029.lzma.bak 2024-04-30T17:32:06.577Z,1714498326.577 [DataOverHttps](INFO): SBD MOMSN=19612563 2024-04-30T17:32:07.617Z,1714498327.617 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:32:07.617Z,1714498327.617 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:32:07.617Z,1714498327.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:32:18.502Z,1714498338.502 [NAL9602](INFO): SBD MO Status=2, MOMSN=16211, MT Status=2, MTMSN=0 2024-04-30T17:32:18.502Z,1714498338.502 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-30T17:32:50.609Z,1714498370.609 [DataOverHttps](IMPORTANT): SBD MTMSN=20240430T173249 2024-04-30T17:32:57.293Z,1714498377.293 [NAL9602](INFO): SBD MO Status=2, MOMSN=16211, MT Status=2, MTMSN=0 2024-04-30T17:32:57.293Z,1714498377.293 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-30T17:32:58.107Z,1714498378.107 [DataOverHttps](INFO): Received command: load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 5 m;run 2024-04-30T17:32:58.145Z,1714498378.145 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl 2024-04-30T17:32:58.146Z,1714498378.146 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl 2024-04-30T17:32:58.146Z,1714498378.146 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl 2024-04-30T17:32:58.148Z,1714498378.148 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl 2024-04-30T17:32:58.809Z,1714498378.809 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into: 60 30 NaN NaN 0 0 10 1 20 1 7 1 2024-04-30T17:32:58.814Z,1714498378.814 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min 2024-04-30T17:32:58.817Z,1714498378.817 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min 2024-04-30T17:32:58.820Z,1714498378.820 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg 2024-04-30T17:32:58.823Z,1714498378.823 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg 2024-04-30T17:32:58.826Z,1714498378.826 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m 2024-04-30T17:32:58.829Z,1714498378.829 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m 2024-04-30T17:32:58.834Z,1714498378.834 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m 2024-04-30T17:32:58.837Z,1714498378.837 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s 2024-04-30T17:32:58.860Z,1714498378.860 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m 2024-04-30T17:32:58.863Z,1714498378.863 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km 2024-04-30T17:32:58.866Z,1714498378.866 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m 2024-04-30T17:32:58.868Z,1714498378.868 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool 2024-04-30T17:32:58.871Z,1714498378.871 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool 2024-04-30T17:32:58.915Z,1714498378.915 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl 2024-04-30T17:32:58.916Z,1714498378.916 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl 2024-04-30T17:32:58.917Z,1714498378.917 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl 2024-04-30T17:32:59.244Z,1714498379.244 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into: 1 10 20 NaN 1 1000 7 30 0 last time_fix was: first GPS update timeout Comms timed out after minutes second GPS update timeout 2024-04-30T17:32:59.250Z,1714498379.250 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h 2024-04-30T17:32:59.253Z,1714498379.253 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min 2024-04-30T17:32:59.256Z,1714498379.256 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg 2024-04-30T17:32:59.259Z,1714498379.259 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s 2024-04-30T17:32:59.262Z,1714498379.262 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s 2024-04-30T17:32:59.265Z,1714498379.265 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s 2024-04-30T17:32:59.268Z,1714498379.268 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min 2024-04-30T17:32:59.271Z,1714498379.271 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min 2024-04-30T17:32:59.282Z,1714498379.283 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-04-30T17:32:59.414Z,1714498379.414 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl 2024-04-30T17:32:59.414Z,1714498379.414 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl 2024-04-30T17:32:59.424Z,1714498379.424 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl 2024-04-30T17:32:59.552Z,1714498379.552 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into: 5 0 200 2000 2024-04-30T17:32:59.561Z,1714498379.561 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m 2024-04-30T17:32:59.568Z,1714498379.568 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m 2024-04-30T17:32:59.571Z,1714498379.571 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m 2024-04-30T17:32:59.578Z,1714498379.578 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m 2024-04-30T17:32:59.579Z,1714498379.579 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope. 2024-04-30T17:32:59.599Z,1714498379.599 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope. 2024-04-30T17:32:59.616Z,1714498379.616 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope. 2024-04-30T17:32:59.636Z,1714498379.636 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl 2024-04-30T17:32:59.636Z,1714498379.636 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl 2024-04-30T17:32:59.637Z,1714498379.637 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl 2024-04-30T17:32:59.686Z,1714498379.686 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into: 2024-04-30T17:32:59.726Z,1714498379.726 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool 2024-04-30T17:32:59.737Z,1714498379.737 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver. 2024-04-30T17:32:59.746Z,1714498379.746 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl 2024-04-30T17:32:59.746Z,1714498379.746 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl 2024-04-30T17:32:59.763Z,1714498379.763 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl 2024-04-30T17:33:00.019Z,1714498380.019 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into: 99999 2024-04-30T17:33:00.028Z,1714498380.028 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool 2024-04-30T17:33:00.030Z,1714498380.030 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool 2024-04-30T17:33:00.034Z,1714498380.034 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool 2024-04-30T17:33:00.046Z,1714498380.046 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool 2024-04-30T17:33:00.050Z,1714498380.050 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 1 bool 2024-04-30T17:33:00.069Z,1714498380.069 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait. 2024-04-30T17:33:00.071Z,1714498380.071 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy. 2024-04-30T17:33:00.107Z,1714498380.107 [transit:MassHold.Pitch](DEBUG): Construct. 2024-04-30T17:33:00.138Z,1714498380.138 [transit:Transit:A.Pitch](DEBUG): Construct. 2024-04-30T17:33:00.160Z,1714498380.160 [transit:Transit:B.SetSpeed](DEBUG): Construct. 2024-04-30T17:33:00.172Z,1714498380.172 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint. 2024-04-30T17:33:00.224Z,1714498380.224 [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 transit { """ Vehicle transits to desired waypoint and can be commanded to use/hold mass or buoyancy. """ arguments { MissionTimeout = 60 minute """ Maximum duration of mission """ NeedCommsTime = 30 minute """ How often to surface for commumications """ Latitude = NaN degree """ Latitude of waypoint to seek. If set to NaN, uses latitude at mission initialization. """ Longitude = NaN degree """ Longitude of waypoint to seek. If set to NaN, uses longitude at mission initialization. """ Northings = 0 meter """ Northward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Eastings = 0 meter """ Eastward distance of the waypoint to seek. If combined with Latitude and/or Longitude, this is an offset from the specified Latitude and/or Longitude. """ Depth = 10 meter """ Depth of flat and level flight during the mission. """ Speed = 1 meter_per_second """ Speed of vehicle (relative to water) during the mission. """ MaxDepth = 20 meter """ Maximum depth for the entire mission. """ MinOffshore = 1 kilometer """ Minimum distance offshore for the entire mission. """ MinAltitude = 7 meter """ Minimum height above the sea floor for the entire mission. """ MassHold = true """ Set to True in order to hold mass at default position, False to allow mass to run on its own. """ BuoyancyHold = true """ Set to True in order to hold buoyancy at neutral position, False to allow buoyancy to run on its own. """ } timeout duration=MissionTimeout insert id="NeedComms" Insert/NeedComms.tl assign in sequence NeedComms:DiveInterval = NeedCommsTime assign in sequence NeedComms:WaitForPitchUp = 1 second insert Insert/StandardEnvelopes.tl assign in sequence StandardEnvelopes:MinAltitude = MinAltitude assign in sequence StandardEnvelopes:MaxDepth = MaxDepth assign in sequence StandardEnvelopes:MinOffshore = MinOffshore insert Insert/BackseatDriver.tl insert Insert/PowerOnly.tl behavior Guidance:Buoyancy id="BuoyancyHold" { run while ( BuoyancyHold ) set position = Control:VerticalControl.buoyancyNeutral } behavior Guidance:Pitch id="MassHold" { run while ( MassHold ) set massPosition = Control:VerticalControl.massDefault } aggregate Transit { run in sequence behavior Guidance:Pitch { run in parallel set depth = Depth } behavior Guidance:SetSpeed { run in parallel set speed = Speed } behavior Guidance:Waypoint id="Wpt1" { run in sequence set latitude = Latitude set longitude = Longitude set northingsDelta = Northings set eastingsDelta = Eastings } call id="PhoneHome" refId="NeedComms" } } 2024-04-30T17:33:00.224Z,1714498380.224 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit 2024-04-30T17:33:07.689Z,1714498387.689 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute 2024-04-30T17:33:07.690Z,1714498387.690 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 15 minute 2024-04-30T17:33:07.691Z,1714498387.691 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree 2024-04-30T17:33:07.692Z,1714498387.692 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree 2024-04-30T17:33:07.693Z,1714498387.693 [CommandExec](IMPORTANT): got command set transit.Depth 5 meter 2024-04-30T17:33:07.693Z,1714498387.693 [CommandExec](IMPORTANT): got command run 2024-04-30T17:33:07.695Z,1714498387.695 [CommandExec](IMPORTANT): Running 2024-04-30T17:33:07.808Z,1714498387.808 [Default] Stopped 2024-04-30T17:33:07.808Z,1714498387.808 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-30T17:33:07.809Z,1714498387.809 [Default:B.GoToSurface] Stopped 2024-04-30T17:33:07.809Z,1714498387.809 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-30T17:33:07.809Z,1714498387.809 [Default:CheckIn] Stopped 2024-04-30T17:33:07.809Z,1714498387.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:33:07.809Z,1714498387.809 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:33:07.809Z,1714498387.809 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:33:07.809Z,1714498387.809 [MissionManager](IMPORTANT): Started mission transit 2024-04-30T17:33:07.809Z,1714498387.809 [transit] Running Loop=1 2024-04-30T17:33:07.809Z,1714498387.809 [transit](DEBUG): Aggregate::initialize transit 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes] Running Loop=1 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent. 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent. 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-04-30T17:33:07.810Z,1714498387.810 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent. 2024-04-30T17:33:07.811Z,1714498387.811 [transit:BackseatDriver] Running Loop=1 2024-04-30T17:33:07.811Z,1714498387.811 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver 2024-04-30T17:33:07.811Z,1714498387.811 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly] Running Loop=1 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly:A] Running Loop=1 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly:B] Running Loop=1 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly:C] Running Loop=1 2024-04-30T17:33:07.819Z,1714498387.819 [transit:PowerOnly:D] Running Loop=1 2024-04-30T17:33:07.820Z,1714498387.820 [transit:PowerOnly:E.Wait] Running Loop=1 2024-04-30T17:33:07.820Z,1714498387.820 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:33:07.820Z,1714498387.820 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-04-30T17:33:07.820Z,1714498387.820 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component. 2024-04-30T17:33:07.820Z,1714498387.820 [transit:MassHold.Pitch] Running Loop=1 2024-04-30T17:33:07.820Z,1714498387.820 [transit:MassHold.Pitch](DEBUG): Initialize. 2024-04-30T17:33:07.820Z,1714498387.820 [transit:B] Running Loop=1 2024-04-30T17:33:07.821Z,1714498387.821 [transit:MassHold.Pitch] Running Loop=1 2024-04-30T17:33:07.821Z,1714498387.821 [transit:BuoyancyHold.Buoyancy] Running Loop=1 2024-04-30T17:33:07.822Z,1714498387.822 [transit:PowerOnly] Running Loop=1 2024-04-30T17:33:07.822Z,1714498387.822 [transit:PowerOnly:D] Stopped 2024-04-30T17:33:07.822Z,1714498387.822 [transit:PowerOnly:C] Stopped 2024-04-30T17:33:07.822Z,1714498387.822 [transit:PowerOnly:B] Stopped 2024-04-30T17:33:07.822Z,1714498387.822 [transit:PowerOnly:A] Stopped 2024-04-30T17:33:07.822Z,1714498387.822 [transit:BackseatDriver] Running Loop=1 2024-04-30T17:33:07.823Z,1714498387.823 [transit:StandardEnvelopes] Running Loop=1 2024-04-30T17:33:07.823Z,1714498387.823 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1 2024-04-30T17:33:07.823Z,1714498387.823 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1 2024-04-30T17:33:07.824Z,1714498387.824 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1 2024-04-30T17:33:07.825Z,1714498387.825 [transit:B] Stopped 2024-04-30T17:33:07.825Z,1714498387.825 [transit:C] Running Loop=1 2024-04-30T17:33:08.207Z,1714498388.207 [transit:C] Stopped 2024-04-30T17:33:08.207Z,1714498388.207 [transit:E] Running Loop=1 2024-04-30T17:33:08.607Z,1714498388.607 [transit:E] Stopped 2024-04-30T17:33:08.607Z,1714498388.607 [transit:F] Running Loop=1 2024-04-30T17:33:09.024Z,1714498389.024 [transit:F] Stopped 2024-04-30T17:33:09.024Z,1714498389.024 [transit:G] Running Loop=1 2024-04-30T17:33:09.414Z,1714498389.414 [transit:G] Stopped 2024-04-30T17:33:09.414Z,1714498389.414 [transit:Transit] Running Loop=1 2024-04-30T17:33:09.414Z,1714498389.414 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit 2024-04-30T17:33:09.414Z,1714498389.414 [transit:Transit:A.Pitch] Running Loop=1 2024-04-30T17:33:09.414Z,1714498389.414 [transit:Transit:A.Pitch](DEBUG): Initialize. 2024-04-30T17:33:09.415Z,1714498389.415 [transit:Transit:B.SetSpeed] Running Loop=1 2024-04-30T17:33:09.415Z,1714498389.415 [transit:Transit:B.SetSpeed](DEBUG): Initialize. 2024-04-30T17:33:09.415Z,1714498389.415 [transit:Transit:Wpt1.Waypoint] Running Loop=1 2024-04-30T17:33:09.415Z,1714498389.415 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent. 2024-04-30T17:33:09.417Z,1714498389.417 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000 2024-04-30T17:33:09.418Z,1714498389.418 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active. 2024-04-30T17:33:09.822Z,1714498389.822 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000 2024-04-30T17:33:09.822Z,1714498389.822 [transit:Transit:B.SetSpeed] Running Loop=1 2024-04-30T17:33:09.823Z,1714498389.823 [transit:Transit:A.Pitch] Running Loop=1 2024-04-30T17:33:09.832Z,1714498389.832 [HorizontalControl](DEBUG): kpHeading = 0.400000 2024-04-30T17:33:09.832Z,1714498389.832 [HorizontalControl](DEBUG): kiHeading = 0.001000 2024-04-30T17:33:09.832Z,1714498389.832 [HorizontalControl](DEBUG): kdHeading = 0.050000 2024-04-30T17:33:36.067Z,1714498416.067 [NAL9602](INFO): SBD MO Status=0, MOMSN=16211, MT Status=0, MTMSN=0 2024-04-30T17:33:36.067Z,1714498416.067 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:34:06.776Z,1714498446.776 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:35:22.625Z,1714498522.625 [DataOverHttps](IMPORTANT): SBD MTMSN=20240430T173521 2024-04-30T17:35:30.375Z,1714498530.375 [DataOverHttps](INFO): Received command: stop 2024-04-30T17:35:30.444Z,1714498530.444 [CommandExec](IMPORTANT): got command stop 2024-04-30T17:35:30.444Z,1714498530.444 [CommandExec](IMPORTANT): Scheduling is paused 2024-04-30T17:35:30.444Z,1714498530.444 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2024-04-30T17:35:30.827Z,1714498530.827 [MissionManager](INFO): MissionManager is completed. 2024-04-30T17:35:30.828Z,1714498530.828 [MissionManager](INFO): Uninitializing Mission transit 2024-04-30T17:35:30.828Z,1714498530.828 [transit] Stopped 2024-04-30T17:35:30.828Z,1714498530.828 [transit](DEBUG): Aggregate::uninitialize transit 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes] Stopped 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent. 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize. 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped 2024-04-30T17:35:30.828Z,1714498530.828 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent. 2024-04-30T17:35:30.828Z,1714498530.828 [transit:BackseatDriver] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver 2024-04-30T17:35:30.829Z,1714498530.829 [transit:BackseatDriver:A.BackseatDriver] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:PowerOnly] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly 2024-04-30T17:35:30.829Z,1714498530.829 [transit:PowerOnly:E.Wait] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:35:30.829Z,1714498530.829 [transit:BuoyancyHold.Buoyancy] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component. 2024-04-30T17:35:30.829Z,1714498530.829 [transit:MassHold.Pitch] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:Transit] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit 2024-04-30T17:35:30.829Z,1714498530.829 [transit:Transit:A.Pitch] Stopped 2024-04-30T17:35:30.829Z,1714498530.829 [transit:Transit:B.SetSpeed] Stopped 2024-04-30T17:35:30.830Z,1714498530.830 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize. 2024-04-30T17:35:30.830Z,1714498530.830 [transit:Transit:Wpt1.Waypoint] Stopped 2024-04-30T17:35:30.830Z,1714498530.830 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent. 2024-04-30T17:35:31.243Z,1714498531.243 [MissionManager](IMPORTANT): Started mission Default 2024-04-30T17:35:31.244Z,1714498531.244 [Default] Running Loop=1 2024-04-30T17:35:31.244Z,1714498531.244 [Default](DEBUG): Aggregate::initialize Default 2024-04-30T17:35:31.244Z,1714498531.244 [Default:B.GoToSurface] Running Loop=1 2024-04-30T17:35:31.244Z,1714498531.244 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-04-30T17:35:31.244Z,1714498531.244 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-04-30T17:35:31.245Z,1714498531.245 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-04-30T17:35:31.245Z,1714498531.245 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-04-30T17:35:31.245Z,1714498531.245 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-04-30T17:35:31.246Z,1714498531.246 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-04-30T17:35:31.246Z,1714498531.246 [Default:A.Wait] Running Loop=1 2024-04-30T17:35:31.246Z,1714498531.246 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:35:44.553Z,1714498544.553 [Default:A.Wait](INFO): Done Waiting. 2024-04-30T17:35:44.553Z,1714498544.553 [Default:A.Wait] Stopped 2024-04-30T17:35:44.553Z,1714498544.553 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:35:44.957Z,1714498544.957 [Default:CheckIn] Running Loop=1 2024-04-30T17:35:44.957Z,1714498544.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-04-30T17:35:44.957Z,1714498544.957 [Default:CheckIn:Read_GPS] Running Loop=1 2024-04-30T17:35:46.966Z,1714498546.966 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173546.00,A,3648.48128,N,12147.27868,W,2.546,240.37,300424,,,A*74 2024-04-30T17:35:46.969Z,1714498546.969 [NAL9602](INFO): GPS fix at 20240430T173546: (36.808021, -121.787978) 2024-04-30T17:35:47.021Z,1714498547.021 [Default:CheckIn:Read_GPS] Stopped 2024-04-30T17:35:47.021Z,1714498547.021 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-04-30T17:35:57.299Z,1714498557.299 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240430T163920/Courier0031.lzma 2024-04-30T17:35:58.301Z,1714498558.301 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Courier0031.lzma.bak 2024-04-30T17:35:58.301Z,1714498558.301 [DataOverHttps](INFO): SBD MOMSN=19612573 2024-04-30T17:36:10.143Z,1714498570.143 [NAL9602](INFO): SBD MO Status=2, MOMSN=16212, MT Status=2, MTMSN=0 2024-04-30T17:36:10.143Z,1714498570.143 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-30T17:36:25.791Z,1714498585.791 [DataOverHttps](INFO): Sending 445 bytes from file Logs/20240430T163920/Express0032.lzma 2024-04-30T17:36:26.793Z,1714498586.793 [DataOverHttps](INFO): Moved sent file to Logs/20240430T163920/Express0032.lzma.bak 2024-04-30T17:36:26.793Z,1714498586.793 [DataOverHttps](INFO): SBD MOMSN=19612577 2024-04-30T17:36:27.912Z,1714498587.912 [Default:CheckIn:Read_Iridium] Stopped 2024-04-30T17:36:27.913Z,1714498587.913 [Default:CheckIn:C.Wait] Running Loop=1 2024-04-30T17:36:27.913Z,1714498587.913 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-04-30T17:36:29.942Z,1714498589.942 [NAL9602](INFO): SBD MO Status=2, MOMSN=16212, MT Status=2, MTMSN=0 2024-04-30T17:36:29.942Z,1714498589.942 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2024-04-30T17:36:46.146Z,1714498606.146 [NAL9602](INFO): SBD MO Status=0, MOMSN=16212, MT Status=0, MTMSN=0 2024-04-30T17:36:46.146Z,1714498606.146 [NAL9602](INFO): No messages in MT queue 2024-04-30T17:37:11.284Z,1714498631.284 [DataOverHttps](IMPORTANT): SBD MTMSN=20240430T173710 2024-04-30T17:37:16.856Z,1714498636.856 [NAL9602](INFO): Not Powering down - fast GPS 2024-04-30T17:37:18.803Z,1714498638.803 [DataOverHttps](INFO): Received command: restart sys 2024-04-30T17:37:18.841Z,1714498638.841 [CommandExec](IMPORTANT): got command restart system 2024-04-30T17:37:20.896Z,1714498640.896 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:20.896Z,1714498640.896 [CommandExec](INFO): Uninitializing the command executive. 2024-04-30T17:37:20.896Z,1714498640.896 [CommandExec](INFO): Uninitializing the command scheduler. 2024-04-30T17:37:20.897Z,1714498640.897 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.027Z,1714498641.027 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2024-04-30T17:37:21.027Z,1714498641.027 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2024-04-30T17:37:21.028Z,1714498641.028 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.028Z,1714498641.028 [NavChartDb](INFO): Join timeout helper Thread ID is 1284 2024-04-30T17:37:21.251Z,1714498641.251 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:21.252Z,1714498641.252 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.267Z,1714498641.267 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2024-04-30T17:37:21.267Z,1714498641.267 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.268Z,1714498641.268 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1285 2024-04-30T17:37:21.495Z,1714498641.495 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:21.495Z,1714498641.495 [WetLabsBB2FL](INFO): Powering down 2024-04-30T17:37:21.496Z,1714498641.496 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.503Z,1714498641.503 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2024-04-30T17:37:21.503Z,1714498641.503 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.504Z,1714498641.504 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1286 2024-04-30T17:37:21.587Z,1714498641.587 [CTD_Seabird](INFO): Powering down 2024-04-30T17:37:21.599Z,1714498641.599 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:21.599Z,1714498641.599 [CTD_Seabird](INFO): Powering down 2024-04-30T17:37:21.611Z,1714498641.611 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.623Z,1714498641.623 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2024-04-30T17:37:21.623Z,1714498641.623 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.624Z,1714498641.624 [DAT](INFO): Join timeout helper Thread ID is 1287 2024-04-30T17:37:21.775Z,1714498641.775 [DAT](INFO): Powering down 2024-04-30T17:37:21.848Z,1714498641.848 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:21.848Z,1714498641.848 [DAT](INFO): Powering down 2024-04-30T17:37:21.849Z,1714498641.849 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.867Z,1714498641.867 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2024-04-30T17:37:21.867Z,1714498641.867 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.867Z,1714498641.867 [Radio_Surface](INFO): Join timeout helper Thread ID is 1288 2024-04-30T17:37:21.919Z,1714498641.919 [Radio_Surface](INFO): Powering down 2024-04-30T17:37:21.920Z,1714498641.920 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:21.920Z,1714498641.920 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.932Z,1714498641.932 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2024-04-30T17:37:21.932Z,1714498641.932 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:21.933Z,1714498641.933 [Onboard](INFO): Join timeout helper Thread ID is 1289 2024-04-30T17:37:22.815Z,1714498642.815 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2024-04-30T17:37:24.587Z,1714498644.587 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:24.588Z,1714498644.588 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:24.604Z,1714498644.604 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2024-04-30T17:37:24.604Z,1714498644.604 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:24.604Z,1714498644.604 [DataOverHttps](INFO): Join timeout helper Thread ID is 1290 2024-04-30T17:37:24.824Z,1714498644.824 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:24.824Z,1714498644.824 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:24.844Z,1714498644.844 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2024-04-30T17:37:24.844Z,1714498644.844 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:24.844Z,1714498644.844 [BackseatComponent](INFO): Join timeout helper Thread ID is 1291 2024-04-30T17:37:25.043Z,1714498645.043 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:25.044Z,1714498645.044 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.064Z,1714498645.064 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2024-04-30T17:37:25.064Z,1714498645.064 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.064Z,1714498645.064 [logger](INFO): Join timeout helper Thread ID is 1292 2024-04-30T17:37:25.091Z,1714498645.091 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:25.092Z,1714498645.092 [logger ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.104Z,1714498645.104 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2024-04-30T17:37:25.104Z,1714498645.104 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.104Z,1714498645.104 [CommandLine](INFO): Join timeout helper Thread ID is 1293 2024-04-30T17:37:25.155Z,1714498645.155 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:25.156Z,1714498645.156 [CommandLine ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.164Z,1714498645.164 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2024-04-30T17:37:25.164Z,1714498645.164 [CommandExec ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.164Z,1714498645.164 [CommandExec](INFO): Join timeout helper Thread ID is 1294 2024-04-30T17:37:25.165Z,1714498645.165 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2024-04-30T17:37:25.165Z,1714498645.165 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.166Z,1714498645.166 [controlThread](INFO): Join timeout helper Thread ID is 1295 2024-04-30T17:37:25.323Z,1714498645.323 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2024-04-30T17:37:25.323Z,1714498645.323 [controlThread](DEBUG): Uninitializing ControlThread 2024-04-30T17:37:25.324Z,1714498645.324 [AHRS_M2](INFO): Powering down 2024-04-30T17:37:25.396Z,1714498645.396 [MultiRay](INFO): Powering down. 2024-04-30T17:37:25.397Z,1714498645.397 [NAL9602](INFO): Powering down 2024-04-30T17:37:25.399Z,1714498645.399 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2024-04-30T17:37:25.400Z,1714498645.400 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2024-04-30T17:37:25.401Z,1714498645.401 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2024-04-30T17:37:25.401Z,1714498645.401 [MissionManager](INFO): Uninitializing Mission Default 2024-04-30T17:37:25.401Z,1714498645.401 [Default] Stopped 2024-04-30T17:37:25.401Z,1714498645.401 [Default](DEBUG): Aggregate::uninitialize Default 2024-04-30T17:37:25.401Z,1714498645.401 [Default:B.GoToSurface] Stopped 2024-04-30T17:37:25.401Z,1714498645.401 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-04-30T17:37:25.401Z,1714498645.401 [Default:CheckIn] Stopped 2024-04-30T17:37:25.402Z,1714498645.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-04-30T17:37:25.402Z,1714498645.402 [Default:CheckIn:C.Wait] Stopped 2024-04-30T17:37:25.402Z,1714498645.402 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-04-30T17:37:25.405Z,1714498645.405 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2024-04-30T17:37:25.405Z,1714498645.405 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2024-04-30T17:37:25.405Z,1714498645.405 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2024-04-30T17:37:25.406Z,1714498645.406 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2024-04-30T17:37:25.406Z,1714498645.406 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2024-04-30T17:37:25.406Z,1714498645.406 [BuoyancyServo](INFO): Powering down 2024-04-30T17:37:25.419Z,1714498645.419 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2024-04-30T17:37:25.419Z,1714498645.419 [ElevatorServo](INFO): Powering down 2024-04-30T17:37:25.420Z,1714498645.420 [MassServo](DEBUG): Uninitialize Mass Servo. 2024-04-30T17:37:25.420Z,1714498645.420 [MassServo](INFO): Powering down 2024-04-30T17:37:25.421Z,1714498645.421 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2024-04-30T17:37:25.421Z,1714498645.421 [RudderServo](INFO): Powering down 2024-04-30T17:37:25.422Z,1714498645.422 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2024-04-30T17:37:25.422Z,1714498645.422 [ThrusterHE](INFO): Powering down 2024-04-30T17:37:25.424Z,1714498645.424 [SBIT](DEBUG): Uninitialize SBIT Component. 2024-04-30T17:37:25.424Z,1714498645.424 [IBIT](DEBUG): Uninitialize IBIT Component. 2024-04-30T17:37:25.424Z,1714498645.424 [CBIT](DEBUG): Uninitialize CBIT Component. 2024-04-30T17:37:25.424Z,1714498645.424 [CBIT](DEBUG): Powering off loads. 2024-04-30T17:37:25.435Z,1714498645.435 [CBIT](DEBUG): Disabling WDT. 2024-04-30T17:37:25.447Z,1714498645.447 [CBIT](DEBUG): Opening all GF detection circuits. 2024-04-30T17:37:25.448Z,1714498645.448 [controlThread ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.479Z,1714498645.479 [DAT ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.485Z,1714498645.485 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.487Z,1714498645.487 [Onboard ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.494Z,1714498645.494 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.541Z,1714498645.541 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.599Z,1714498645.599 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.603Z,1714498645.603 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.669Z,1714498645.669 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2024-04-30T17:37:25.718Z,1714498645.718 [logger ThreadHandler](INFO): Thread cancelled.