2024-12-18T23:17:48.338Z,1734563868.338 [Supervisor](DEBUG): Initializing supervisor.
2024-12-18T23:17:48.342Z,1734563868.342 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-12-18T23:17:48.342Z,1734563868.342 [SyncHandler](INFO): Protected caller Thread ID is 844
2024-12-18T23:17:48.343Z,1734563868.343 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-12-18T23:17:48.344Z,1734563868.344 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-12-18T23:17:48.344Z,1734563868.344 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 845
2024-12-18T23:17:48.348Z,1734563868.348 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-12-18T23:17:48.365Z,1734563868.365 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-12-18T23:17:48.366Z,1734563868.366 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-12-18T23:17:48.367Z,1734563868.367 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 846
2024-12-18T23:17:48.371Z,1734563868.371 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-12-18T23:17:48.372Z,1734563868.372 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-12-18T23:17:48.372Z,1734563868.372 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 847
2024-12-18T23:17:48.374Z,1734563868.374 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-12-18T23:17:48.375Z,1734563868.375 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-12-18T23:17:48.376Z,1734563868.376 [logger ThreadHandler](INFO): Protected caller Thread ID is 848
2024-12-18T23:17:48.379Z,1734563868.379 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-12-18T23:17:48.380Z,1734563868.380 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-12-18T23:17:48.383Z,1734563868.383 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-12-18T23:17:48.634Z,1734563868.634 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-12-18T23:17:48.636Z,1734563868.636 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-12-18T23:17:48.856Z,1734563868.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-12-18T23:17:48.857Z,1734563868.857 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-12-18T23:17:48.961Z,1734563868.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-12-18T23:17:48.962Z,1734563868.962 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-12-18T23:17:49.152Z,1734563869.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-12-18T23:17:49.154Z,1734563869.154 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-12-18T23:17:49.655Z,1734563869.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-12-18T23:17:49.657Z,1734563869.657 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-12-18T23:17:49.773Z,1734563869.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-12-18T23:17:49.774Z,1734563869.774 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-12-18T23:17:50.052Z,1734563870.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-12-18T23:17:50.054Z,1734563870.054 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-12-18T23:17:50.282Z,1734563870.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-12-18T23:17:50.283Z,1734563870.283 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-12-18T23:17:50.823Z,1734563870.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-12-18T23:17:50.825Z,1734563870.825 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-12-18T23:17:51.247Z,1734563871.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-12-18T23:17:51.247Z,1734563871.247 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-12-18T23:17:51.337Z,1734563871.337 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-12-18T23:17:51.719Z,1734563871.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-12-18T23:17:51.719Z,1734563871.719 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-12-18T23:17:52.785Z,1734563872.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-12-18T23:17:52.785Z,1734563872.785 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-12-18T23:18:02.134Z,1734563882.134 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-12-18T23:18:02.135Z,1734563882.135 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-12-18T23:18:02.489Z,1734563882.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-12-18T23:18:02.491Z,1734563882.491 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-12-18T23:18:02.585Z,1734563882.585 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2024-12-18T23:18:02.587Z,1734563882.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2024-12-18T23:18:02.683Z,1734563882.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2024-12-18T23:18:02.802Z,1734563882.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2024-12-18T23:18:02.918Z,1734563882.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2024-12-18T23:18:03.017Z,1734563883.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2024-12-18T23:18:03.114Z,1734563883.114 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2024-12-18T23:18:03.230Z,1734563883.230 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2024-12-18T23:18:03.387Z,1734563883.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2024-12-18T23:18:03.570Z,1734563883.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2024-12-18T23:18:03.676Z,1734563883.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2024-12-18T23:18:03.953Z,1734563883.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-12-18T23:18:03.953Z,1734563883.953 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-12-18T23:18:03.965Z,1734563883.965 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-12-18T23:18:04.390Z,1734563884.390 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-12-18T23:18:04.391Z,1734563884.391 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-12-18T23:18:05.238Z,1734563885.238 [AHRS_M2] Loaded
2024-12-18T23:18:05.238Z,1734563885.238 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-12-18T23:18:05.491Z,1734563885.491 [BackseatComponent] Loaded
2024-12-18T23:18:05.492Z,1734563885.492 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-12-18T23:18:05.493Z,1734563885.493 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408A44E0
2024-12-18T23:18:05.493Z,1734563885.493 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 928
2024-12-18T23:18:05.496Z,1734563885.496 [LcmUniversalReporter] Loaded
2024-12-18T23:18:05.496Z,1734563885.496 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-12-18T23:18:05.503Z,1734563885.503 [BioacousticsDataBridge] Loaded
2024-12-18T23:18:05.503Z,1734563885.503 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-12-18T23:18:06.321Z,1734563886.321 [BPC1] Loaded
2024-12-18T23:18:06.321Z,1734563886.321 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-12-18T23:18:06.417Z,1734563886.417 [DAT] Loaded
2024-12-18T23:18:06.417Z,1734563886.417 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-12-18T23:18:06.418Z,1734563886.418 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408D44E0
2024-12-18T23:18:06.418Z,1734563886.418 [DAT ThreadHandler](INFO): Protected caller Thread ID is 929
2024-12-18T23:18:06.663Z,1734563886.663 [DataOverHttps] Loaded
2024-12-18T23:18:06.663Z,1734563886.663 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-12-18T23:18:06.664Z,1734563886.664 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409044E0
2024-12-18T23:18:06.664Z,1734563886.664 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 930
2024-12-18T23:18:06.687Z,1734563886.687 [Depth_Keller] Loaded
2024-12-18T23:18:06.687Z,1734563886.687 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-12-18T23:18:06.752Z,1734563886.752 [NAL9602] Loaded
2024-12-18T23:18:06.752Z,1734563886.752 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-12-18T23:18:06.782Z,1734563886.782 [Onboard] Loaded
2024-12-18T23:18:06.782Z,1734563886.782 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-12-18T23:18:06.783Z,1734563886.783 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409344E0
2024-12-18T23:18:06.784Z,1734563886.784 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 931
2024-12-18T23:18:06.798Z,1734563886.798 [Power24vConverter] Loaded
2024-12-18T23:18:06.798Z,1734563886.798 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-12-18T23:18:06.815Z,1734563886.815 [Radio_Surface] Loaded
2024-12-18T23:18:06.815Z,1734563886.815 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-12-18T23:18:06.816Z,1734563886.816 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409644E0
2024-12-18T23:18:06.817Z,1734563886.817 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 932
2024-12-18T23:18:06.832Z,1734563886.832 [Sonardyne_Nano] Loaded
2024-12-18T23:18:06.832Z,1734563886.832 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-12-18T23:18:06.833Z,1734563886.833 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-12-18T23:18:09.401Z,1734563889.401 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-12-18T23:18:09.681Z,1734563889.681 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-12-18T23:18:09.682Z,1734563889.682 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-12-18T23:18:09.760Z,1734563889.760 [VerticalControl](DEBUG): Construct VerticalControl.
2024-12-18T23:18:09.819Z,1734563889.819 [VerticalControl] Loaded
2024-12-18T23:18:09.820Z,1734563889.820 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-12-18T23:18:09.823Z,1734563889.823 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-12-18T23:18:09.871Z,1734563889.871 [HorizontalControl] Loaded
2024-12-18T23:18:09.871Z,1734563889.871 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-12-18T23:18:09.874Z,1734563889.874 [SpeedControl](DEBUG): Construct SpeedControl.
2024-12-18T23:18:09.877Z,1734563889.877 [SpeedControl] Loaded
2024-12-18T23:18:09.877Z,1734563889.877 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-12-18T23:18:09.880Z,1734563889.880 [LoopControl](DEBUG): Construct LoopControl.
2024-12-18T23:18:09.880Z,1734563889.880 [LoopControl] Loaded
2024-12-18T23:18:09.881Z,1734563889.881 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-12-18T23:18:09.881Z,1734563889.881 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-12-18T23:18:09.882Z,1734563889.882 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-12-18T23:18:09.898Z,1734563889.898 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-12-18T23:18:09.899Z,1734563889.899 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-12-18T23:18:09.943Z,1734563889.943 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-12-18T23:18:09.944Z,1734563889.944 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-12-18T23:18:10.089Z,1734563890.089 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-12-18T23:18:10.089Z,1734563890.089 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-12-18T23:18:10.103Z,1734563890.103 [NavChart] Loaded
2024-12-18T23:18:10.103Z,1734563890.103 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-12-18T23:18:10.109Z,1734563890.109 [UniversalFixResidualReporter] Loaded
2024-12-18T23:18:10.109Z,1734563890.109 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-12-18T23:18:10.110Z,1734563890.110 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-12-18T23:18:10.110Z,1734563890.110 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-12-18T23:18:10.302Z,1734563890.302 [SBIT](DEBUG): Construct Startup Built In Test.
2024-12-18T23:18:10.311Z,1734563890.311 [SBIT] Loaded
2024-12-18T23:18:10.311Z,1734563890.311 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-12-18T23:18:10.314Z,1734563890.314 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-12-18T23:18:10.327Z,1734563890.327 [IBIT] Loaded
2024-12-18T23:18:10.327Z,1734563890.327 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-12-18T23:18:10.333Z,1734563890.333 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-12-18T23:18:10.441Z,1734563890.441 [CBIT] Loaded
2024-12-18T23:18:10.441Z,1734563890.441 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-12-18T23:18:10.441Z,1734563890.441 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-12-18T23:18:10.442Z,1734563890.442 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-12-18T23:18:10.669Z,1734563890.669 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-12-18T23:18:10.670Z,1734563890.670 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-12-18T23:18:10.842Z,1734563890.842 [BuoyancyServo] Loaded
2024-12-18T23:18:10.842Z,1734563890.842 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-12-18T23:18:10.865Z,1734563890.865 [ElevatorServo] Loaded
2024-12-18T23:18:10.866Z,1734563890.866 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-12-18T23:18:10.887Z,1734563890.887 [MassServo] Loaded
2024-12-18T23:18:10.888Z,1734563890.888 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-12-18T23:18:10.908Z,1734563890.908 [RudderServo] Loaded
2024-12-18T23:18:10.909Z,1734563890.909 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-12-18T23:18:10.926Z,1734563890.926 [ThrusterHE] Loaded
2024-12-18T23:18:10.927Z,1734563890.927 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-12-18T23:18:10.927Z,1734563890.927 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-12-18T23:18:10.928Z,1734563890.928 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-12-18T23:18:11.163Z,1734563891.163 [CTD_Seabird] Loaded
2024-12-18T23:18:11.163Z,1734563891.163 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-12-18T23:18:11.164Z,1734563891.164 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2024-12-18T23:18:11.165Z,1734563891.165 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 933
2024-12-18T23:18:11.187Z,1734563891.187 [PAR_Licor] Loaded
2024-12-18T23:18:11.187Z,1734563891.187 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-12-18T23:18:11.234Z,1734563891.234 [WetLabsBB2FL] Loaded
2024-12-18T23:18:11.234Z,1734563891.234 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-12-18T23:18:11.235Z,1734563891.235 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0
2024-12-18T23:18:11.235Z,1734563891.235 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 934
2024-12-18T23:18:11.236Z,1734563891.236 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-12-18T23:18:11.237Z,1734563891.237 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-12-18T23:18:11.312Z,1734563891.312 [DepthRateCalculator] Loaded
2024-12-18T23:18:11.313Z,1734563891.313 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-12-18T23:18:11.318Z,1734563891.318 [PitchRateCalculator] Loaded
2024-12-18T23:18:11.318Z,1734563891.318 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-12-18T23:18:11.327Z,1734563891.327 [SpeedCalculator] Loaded
2024-12-18T23:18:11.328Z,1734563891.328 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-12-18T23:18:11.332Z,1734563891.332 [YawRateCalculator] Loaded
2024-12-18T23:18:11.332Z,1734563891.332 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-12-18T23:18:11.352Z,1734563891.352 [ElevatorOffsetCalculator] Loaded
2024-12-18T23:18:11.352Z,1734563891.352 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-12-18T23:18:11.353Z,1734563891.353 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-12-18T23:18:11.353Z,1734563891.353 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-12-18T23:18:11.442Z,1734563891.442 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-12-18T23:18:11.449Z,1734563891.449 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-12-18T23:18:11.452Z,1734563891.452 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-12-18T23:18:11.463Z,1734563891.463 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-12-18T23:18:11.465Z,1734563891.465 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C504E0
2024-12-18T23:18:11.465Z,1734563891.465 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 935
2024-12-18T23:18:11.469Z,1734563891.469 [Supervisor](INFO): Main Thread ID is 835
2024-12-18T23:18:11.469Z,1734563891.469 [Supervisor](DEBUG): Running supervisor.
2024-12-18T23:18:11.470Z,1734563891.470 [CommandExec ThreadHandler](INFO): Handler Thread ID is 936
2024-12-18T23:18:11.470Z,1734563891.470 [CommandExec](INFO): Initializing the command executive.
2024-12-18T23:18:11.472Z,1734563891.472 [CommandLine ThreadHandler](INFO): Handler Thread ID is 937
2024-12-18T23:18:11.474Z,1734563891.474 [controlThread ThreadHandler](INFO): Handler Thread ID is 938
2024-12-18T23:18:11.474Z,1734563891.474 [controlThread](DEBUG): Initializing ControlThread
2024-12-18T23:18:11.481Z,1734563891.481 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-12-18T23:18:11.482Z,1734563891.482 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-12-18T23:18:11.483Z,1734563891.483 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-12-18T23:18:11.483Z,1734563891.483 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-12-18T23:18:11.484Z,1734563891.484 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-12-18T23:18:11.484Z,1734563891.484 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-12-18T23:18:11.485Z,1734563891.485 [SBIT](INFO): Initialize SBIT Component.
2024-12-18T23:18:11.485Z,1734563891.485 [SBIT](IMPORTANT): git: 2024-12-17_A
2024-12-18T23:18:11.485Z,1734563891.485 [SBIT](INFO): git hash: 35d3830406fd08eef8bec72d532b8ec94802bb61
2024-12-18T23:18:11.486Z,1734563891.486 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-12-18T23:18:11.487Z,1734563891.487 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Dec 4 01:17:04 UTC 2024
2024-12-18T23:18:11.488Z,1734563891.488 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2024-12-18T23:18:11.489Z,1734563891.489 [IBIT](INFO): Initialize IBIT Component.
2024-12-18T23:18:11.490Z,1734563891.490 [CBIT](DEBUG): Initialize CBIT Component.
2024-12-18T23:18:11.491Z,1734563891.491 [logger ThreadHandler](INFO): Handler Thread ID is 939
2024-12-18T23:18:11.504Z,1734563891.504 [CBIT](DEBUG): Initialized mux pins.
2024-12-18T23:18:11.504Z,1734563891.504 [CBIT](DEBUG): Initializing the watchdog timer.
2024-12-18T23:18:11.508Z,1734563891.508 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 940
2024-12-18T23:18:11.516Z,1734563891.516 [DAT ThreadHandler](INFO): Handler Thread ID is 941
2024-12-18T23:18:11.517Z,1734563891.517 [DAT](INFO): Powering up
2024-12-18T23:18:11.517Z,1734563891.517 [DAT](DEBUG): Initializing DAT.
2024-12-18T23:18:11.521Z,1734563891.521 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 943
2024-12-18T23:18:11.523Z,1734563891.523 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-12-18T23:18:11.528Z,1734563891.528 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-12-18T23:18:11.529Z,1734563891.529 [CBIT](DEBUG): Initializing heartbeat.
2024-12-18T23:18:11.536Z,1734563891.536 [Onboard ThreadHandler](INFO): Handler Thread ID is 944
2024-12-18T23:18:11.554Z,1734563891.554 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 945
2024-12-18T23:18:11.585Z,1734563891.585 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 946
2024-12-18T23:18:11.585Z,1734563891.585 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-12-18T23:18:11.592Z,1734563891.592 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 948
2024-12-18T23:18:11.596Z,1734563891.596 [WetLabsBB2FL](INFO): Powering up
2024-12-18T23:18:11.597Z,1734563891.597 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 950
2024-12-18T23:18:11.600Z,1734563891.600 [CBIT](DEBUG): Deactivating GF circuits.
2024-12-18T23:18:11.600Z,1734563891.600 [CBIT](DEBUG): Deactivating emergency mode.
2024-12-18T23:18:11.608Z,1734563891.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-12-18T23:18:11.608Z,1734563891.608 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-12-18T23:18:11.608Z,1734563891.608 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-12-18T23:18:11.609Z,1734563891.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-12-18T23:18:11.609Z,1734563891.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-12-18T23:18:11.609Z,1734563891.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-12-18T23:18:11.609Z,1734563891.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-12-18T23:18:11.609Z,1734563891.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-12-18T23:18:11.640Z,1734563891.640 [CBIT](DEBUG): Backplane powered.
2024-12-18T23:18:11.644Z,1734563891.644 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-12-18T23:18:11.645Z,1734563891.645 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-12-18T23:18:11.645Z,1734563891.645 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-12-18T23:18:11.645Z,1734563891.645 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-12-18T23:18:11.646Z,1734563891.646 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-12-18T23:18:11.647Z,1734563891.647 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-12-18T23:18:11.647Z,1734563891.647 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-12-18T23:18:11.648Z,1734563891.648 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-12-18T23:18:11.655Z,1734563891.655 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-12-18T23:18:11.736Z,1734563891.736 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-12-18T23:18:11.741Z,1734563891.741 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-18T23:18:11.787Z,1734563891.787 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-12-18T23:18:11.787Z,1734563891.787 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-12-18T23:18:11.802Z,1734563891.802 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-12-18T23:18:11.809Z,1734563891.809 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-12-18T23:18:11.960Z,1734563891.960 [Radio_Surface](INFO): Powering up
2024-12-18T23:18:12.109Z,1734563892.109 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-12-18T23:18:12.115Z,1734563892.115 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-12-18T23:18:12.133Z,1734563892.133 [Default:A.Wait](DEBUG): Construct Wait.
2024-12-18T23:18:12.135Z,1734563892.135 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-18T23:18:12.175Z,1734563892.175 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-12-18T23:18:12.178Z,1734563892.178 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-12-18T23:18:12.216Z,1734563892.216 [Default:E.Execute](DEBUG): Construct Execute.
2024-12-18T23:18:12.219Z,1734563892.219 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-12-18T23:18:12.223Z,1734563892.224 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:18:12.244Z,1734563892.244 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-12-18T23:18:12.414Z,1734563892.414 [Power24vConverter](INFO): Powering up.
2024-12-18T23:18:12.415Z,1734563892.415 [Sonardyne_Nano](INFO): Initializing.
2024-12-18T23:18:12.470Z,1734563892.470 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-12-18T23:18:12.476Z,1734563892.476 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-12-18T23:18:12.477Z,1734563892.477 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-12-18T23:18:12.484Z,1734563892.484 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-12-18T23:18:12.485Z,1734563892.485 [MassServo](DEBUG): Initializing EZServoServo.
2024-12-18T23:18:12.492Z,1734563892.492 [MassServo](DEBUG): Initializing MassServo.
2024-12-18T23:18:12.493Z,1734563892.493 [RudderServo](DEBUG): Initializing EZServoServo.
2024-12-18T23:18:12.500Z,1734563892.500 [RudderServo](DEBUG): Initializing RudderServo.
2024-12-18T23:18:12.501Z,1734563892.501 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:18:12.509Z,1734563892.509 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:18:13.704Z,1734563893.704 [WetLabsBB2FL](INFO): Powering down
2024-12-18T23:18:14.658Z,1734563894.658 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-12-18T23:18:15.894Z,1734563895.894 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2024-12-18T23:18:17.977Z,1734563897.977 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-12-18T23:18:18.616Z,1734563898.616 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-12-18T23:18:24.629Z,1734563904.629 [DAT](INFO): DAT read:
2024-12-18T23:18:24.630Z,1734563904.630 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-12-18T23:18:26.141Z,1734563906.141 [DAT](INFO): DAT read: MF Frequency Band
2024-12-18T23:18:26.142Z,1734563906.142 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-12-18T23:18:26.143Z,1734563906.143 [DAT](INFO): DAT read: Dec 18 2024 23:15:11
2024-12-18T23:18:27.161Z,1734563907.161 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-12-18T23:18:27.163Z,1734563907.163 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-12-18T23:18:27.163Z,1734563907.163 [DAT](INFO): commRate: 800
2024-12-18T23:18:29.228Z,1734563909.228 [DAT](INFO): entering command mode
2024-12-18T23:18:29.428Z,1734563909.428 [DAT](INFO): DAT read:
2024-12-18T23:18:29.429Z,1734563909.429 [DAT](INFO): DAT read: user:1>
2024-12-18T23:18:29.429Z,1734563909.429 [DAT](INFO): setting verbose to 3
2024-12-18T23:18:29.681Z,1734563909.681 [DAT](INFO): DAT read: user:1>
2024-12-18T23:18:29.682Z,1734563909.682 [DAT](INFO): DAT read: Verbose | 3
2024-12-18T23:18:29.682Z,1734563909.682 [DAT](INFO): set verbose to 3
2024-12-18T23:18:29.682Z,1734563909.682 [DAT](INFO): setting DatVerbose to 27440
2024-12-18T23:18:29.933Z,1734563909.933 [DAT](INFO): DAT read: user:2>
2024-12-18T23:18:29.934Z,1734563909.934 [DAT](INFO): DAT read: DatVerbose | 27440
2024-12-18T23:18:29.934Z,1734563909.934 [DAT](INFO): set DatVerbose to 27440
2024-12-18T23:18:29.934Z,1734563909.934 [DAT](INFO): setting transmit power to 8
2024-12-18T23:18:30.185Z,1734563910.185 [DAT](INFO): DAT read: user:3>
2024-12-18T23:18:30.186Z,1734563910.186 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-12-18T23:18:30.186Z,1734563910.186 [DAT](INFO): set transmit power to 8
2024-12-18T23:18:30.186Z,1734563910.186 [DAT](INFO): setting local address to 2
2024-12-18T23:18:30.437Z,1734563910.437 [DAT](INFO): DAT read: user:4>
2024-12-18T23:18:30.438Z,1734563910.438 [DAT](INFO): DAT read: LocalAddr | 2
2024-12-18T23:18:30.438Z,1734563910.438 [DAT](INFO): set local address to 2
2024-12-18T23:18:30.439Z,1734563910.439 [DAT](INFO): Setting time to: 23:18:30 And date to:12/18/2024
2024-12-18T23:18:30.689Z,1734563910.689 [DAT](INFO): DAT read: user:5>
2024-12-18T23:18:30.690Z,1734563910.690 [DAT](INFO): DAT read: Wed Dec 18, 2024 23:18:30
2024-12-18T23:18:30.690Z,1734563910.690 [DAT](INFO): Local DAT time set to Wed Dec 18, 2024 23:18:30
2024-12-18T23:18:31.193Z,1734563911.193 [DAT](FAULT): LCB fault: Current Limiter Activated.
2024-12-18T23:18:31.193Z,1734563911.193 [DAT] Hardware Fault, FailCount= 1
2024-12-18T23:18:31.193Z,1734563911.193 [DAT](ERROR): Hardware Fault
2024-12-18T23:18:31.193Z,1734563911.193 [DAT](INFO): Powering down
2024-12-18T23:18:31.357Z,1734563911.357 [CBIT](ERROR): Hardware Fault in component: DAT
2024-12-18T23:18:31.672Z,1734563911.672 [DAT](INFO): Powering down
2024-12-18T23:18:32.076Z,1734563912.076 [DAT](FAULT): LCB fault: Current Limiter Activated.
2024-12-18T23:18:32.534Z,1734563912.534 [CBIT](INFO): Clearing failed state for component DAT
2024-12-18T23:18:32.534Z,1734563912.534 [DAT] No Fault, FailCount= 1
2024-12-18T23:18:34.916Z,1734563914.916 [DAT](INFO): Powering up
2024-12-18T23:18:34.916Z,1734563914.916 [DAT](DEBUG): Initializing DAT.
2024-12-18T23:18:36.881Z,1734563916.881 [NAL9602](INFO): Powering up NAL9602
2024-12-18T23:18:46.524Z,1734563926.524 [DAT](INFO): DAT read:
2024-12-18T23:18:46.525Z,1734563926.525 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-12-18T23:18:47.794Z,1734563927.794 [NAL9602](INFO): NAL9602 initialized
2024-12-18T23:18:48.037Z,1734563928.037 [DAT](INFO): DAT read: MF Frequency Band
2024-12-18T23:18:48.038Z,1734563928.038 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-12-18T23:18:48.039Z,1734563928.039 [DAT](INFO): DAT read: Dec 18 2024 23:18:43
2024-12-18T23:18:49.048Z,1734563929.048 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-12-18T23:18:49.049Z,1734563929.049 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2024-12-18T23:18:49.050Z,1734563929.050 [DAT](INFO): commRate: 800
2024-12-18T23:18:51.116Z,1734563931.116 [DAT](INFO): entering command mode
2024-12-18T23:18:51.312Z,1734563931.312 [DAT](INFO): DAT read:
2024-12-18T23:18:51.313Z,1734563931.313 [DAT](INFO): DAT read: user:1>
2024-12-18T23:18:51.313Z,1734563931.313 [DAT](INFO): setting verbose to 3
2024-12-18T23:18:51.565Z,1734563931.565 [DAT](INFO): DAT read: user:1>
2024-12-18T23:18:51.566Z,1734563931.566 [DAT](INFO): DAT read: Verbose | 3
2024-12-18T23:18:51.566Z,1734563931.566 [DAT](INFO): set verbose to 3
2024-12-18T23:18:51.566Z,1734563931.566 [DAT](INFO): setting DatVerbose to 27440
2024-12-18T23:18:51.817Z,1734563931.817 [DAT](INFO): DAT read: user:2>
2024-12-18T23:18:51.818Z,1734563931.818 [DAT](INFO): DAT read: DatVerbose | 27440
2024-12-18T23:18:51.818Z,1734563931.818 [DAT](INFO): set DatVerbose to 27440
2024-12-18T23:18:51.818Z,1734563931.818 [DAT](INFO): setting transmit power to 8
2024-12-18T23:18:52.069Z,1734563932.069 [DAT](INFO): DAT read: user:3>
2024-12-18T23:18:52.070Z,1734563932.070 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-12-18T23:18:52.070Z,1734563932.070 [DAT](INFO): set transmit power to 8
2024-12-18T23:18:52.070Z,1734563932.070 [DAT](INFO): setting local address to 2
2024-12-18T23:18:52.321Z,1734563932.321 [DAT](INFO): DAT read: user:4>
2024-12-18T23:18:52.321Z,1734563932.321 [DAT](INFO): DAT read: LocalAddr | 2
2024-12-18T23:18:52.322Z,1734563932.322 [DAT](INFO): set local address to 2
2024-12-18T23:18:52.323Z,1734563932.323 [DAT](INFO): Setting time to: 23:18:52 And date to:12/18/2024
2024-12-18T23:18:52.573Z,1734563932.573 [DAT](INFO): DAT read: user:5>
2024-12-18T23:18:52.574Z,1734563932.574 [DAT](INFO): DAT read: Wed Dec 18, 2024 23:18:52
2024-12-18T23:18:52.574Z,1734563932.574 [DAT](INFO): Local DAT time set to Wed Dec 18, 2024 23:18:52
2024-12-18T23:19:06.014Z,1734563946.014 [SBIT](IMPORTANT): Beginning Startup BIT
2024-12-18T23:19:06.019Z,1734563946.019 [CBIT](IMPORTANT): Beginning ground fault scan
2024-12-18T23:19:17.404Z,1734563957.404 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000598
CHAN A1 (24V): -0.000471
CHAN A2 (12V): 0.000293
CHAN A3 (5V): -0.001495
CHAN B0 (3.3V): -0.001228
CHAN B1 (3.15aV): -0.000934
CHAN B2 (3.15bV): -0.001718
CHAN B3 (GND): -0.002464
OPEN: 0.004703
Full Scale: +/- 1 mA
2024-12-18T23:19:54.317Z,1734563994.317 [CommandExec](IMPORTANT): got command strobe off
2024-12-18T23:19:54.318Z,1734563994.318 [CommandExec](IMPORTANT): Deactivating strobe
2024-12-18T23:20:00.310Z,1734564000.310 [SBIT](IMPORTANT): SBIT PASSED
2024-12-18T23:20:00.310Z,1734564000.310 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-12-18T23:20:00.311Z,1734564000.311 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;
2024-12-18T23:20:00.311Z,1734564000.311 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter;
2024-12-18T23:20:00.311Z,1734564000.311 [SBIT](IMPORTANT): Express none platform_roll_angle;
2024-12-18T23:20:00.311Z,1734564000.311 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=331.746898 cubic_centimeter;
2024-12-18T23:20:00.311Z,1734564000.311 [SBIT](IMPORTANT): VerticalControl.massDefault=8.562257 millimeter;
2024-12-18T23:20:00.645Z,1734564000.645 [MissionManager](IMPORTANT): Started mission Startup
2024-12-18T23:20:00.645Z,1734564000.645 [Startup] Running Loop=1
2024-12-18T23:20:00.645Z,1734564000.645 [Startup](DEBUG): Aggregate::initialize Startup
2024-12-18T23:20:00.646Z,1734564000.646 [Startup:A.GoToSurface] Running Loop=1
2024-12-18T23:20:00.646Z,1734564000.646 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-12-18T23:20:00.646Z,1734564000.646 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-12-18T23:20:00.647Z,1734564000.647 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-12-18T23:20:00.647Z,1734564000.647 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-12-18T23:20:00.647Z,1734564000.647 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-12-18T23:20:00.648Z,1734564000.648 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-12-18T23:20:00.648Z,1734564000.648 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-12-18T23:20:00.650Z,1734564000.650 [Startup:StartupSatComms] Running Loop=1
2024-12-18T23:20:00.650Z,1734564000.650 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-12-18T23:20:00.650Z,1734564000.650 [Startup:StartupSatComms:A] Running Loop=1
2024-12-18T23:20:01.050Z,1734564001.050 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-12-18T23:20:22.326Z,1734564022.326 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004689
2024-12-18T23:21:00.825Z,1734564060.825 [Startup:StartupSatComms:A](INFO): Timed out from 2024-12-18T23:20:00.7Z
2024-12-18T23:21:00.826Z,1734564060.826 [Startup:StartupSatComms:A] Stopped
2024-12-18T23:21:00.826Z,1734564060.826 [Startup:StartupSatComms:B] Running Loop=1
2024-12-18T23:21:01.230Z,1734564061.230 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-12-18T23:21:11.750Z,1734564071.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-12-18T23:21:11.750Z,1734564071.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:21:11.761Z,1734564071.761 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:21:12.146Z,1734564072.146 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:21:12.146Z,1734564072.146 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-12-18T23:21:13.442Z,1734564073.442 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241218T215043/Courier0022.lzma
2024-12-18T23:21:14.585Z,1734564074.585 [DataOverHttps](INFO): Moved sent file to Logs/20241218T215043/Courier0022.lzma.bak
2024-12-18T23:21:14.585Z,1734564074.585 [DataOverHttps](INFO): SBD MOMSN=23824507
2024-12-18T23:21:29.482Z,1734564089.482 [CommandExec](IMPORTANT): got command get Onboard.Pressure pound_per_square_inch
2024-12-18T23:21:29.483Z,1734564089.483 [CommandExec](IMPORTANT): Onboard.Pressure 9.672902 psi
2024-12-18T23:21:31.400Z,1734564091.400 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241218T231748/Courier0000.lzma
2024-12-18T23:21:32.402Z,1734564092.402 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0000.lzma.bak
2024-12-18T23:21:32.402Z,1734564092.402 [DataOverHttps](INFO): SBD MOMSN=23824509
2024-12-18T23:21:34.901Z,1734564094.901 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-12-18T23:21:34.901Z,1734564094.901 [CommandExec](IMPORTANT): Onboard.Humidity 20.388231 %
2024-12-18T23:21:50.460Z,1734564110.460 [DataOverHttps](INFO): Sending 330 bytes from file Logs/20241218T215043/Express0023.lzma
2024-12-18T23:21:55.670Z,1734564115.670 [DataOverHttps](INFO): Moved sent file to Logs/20241218T215043/Express0023.lzma.bak
2024-12-18T23:21:55.671Z,1734564115.671 [DataOverHttps](INFO): SBD MOMSN=23824512
2024-12-18T23:21:56.262Z,1734564116.262 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-18T23:22:01.042Z,1734564121.042 [Startup:StartupSatComms:B](INFO): Timed out from 2024-12-18T23:21:00.8Z
2024-12-18T23:22:01.042Z,1734564121.042 [Startup:StartupSatComms:B] Stopped
2024-12-18T23:22:01.042Z,1734564121.042 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-12-18T23:22:01.042Z,1734564121.042 [Startup:StartupSatComms] Stopped
2024-12-18T23:22:01.043Z,1734564121.043 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-12-18T23:22:01.043Z,1734564121.043 [Startup](INFO): Completed Startup
2024-12-18T23:22:01.048Z,1734564121.048 [MissionManager](INFO): Startup is completed.
2024-12-18T23:22:01.048Z,1734564121.048 [MissionManager](INFO): Uninitializing Mission Startup
2024-12-18T23:22:01.049Z,1734564121.049 [Startup] Stopped
2024-12-18T23:22:01.049Z,1734564121.049 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-12-18T23:22:01.049Z,1734564121.049 [Startup:A.GoToSurface] Stopped
2024-12-18T23:22:01.049Z,1734564121.049 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-12-18T23:22:01.437Z,1734564121.437 [MissionManager](IMPORTANT): Started mission Default
2024-12-18T23:22:01.437Z,1734564121.437 [Default] Running Loop=1
2024-12-18T23:22:01.438Z,1734564121.438 [Default](DEBUG): Aggregate::initialize Default
2024-12-18T23:22:01.438Z,1734564121.438 [Default:B.GoToSurface] Running Loop=1
2024-12-18T23:22:01.438Z,1734564121.438 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-12-18T23:22:01.438Z,1734564121.438 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-12-18T23:22:01.438Z,1734564121.438 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-12-18T23:22:01.439Z,1734564121.439 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-12-18T23:22:01.439Z,1734564121.439 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-12-18T23:22:01.439Z,1734564121.439 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-12-18T23:22:01.468Z,1734564121.468 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-12-18T23:22:01.468Z,1734564121.468 [Default:A.Wait] Running Loop=1
2024-12-18T23:22:01.472Z,1734564121.472 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:22:09.187Z,1734564129.187 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-18T23:22:12.297Z,1734564132.297 [DataOverHttps](INFO): Sending 906 bytes from file Logs/20241218T231748/Express0001.lzma
2024-12-18T23:22:13.298Z,1734564133.298 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0001.lzma.bak
2024-12-18T23:22:13.298Z,1734564133.298 [DataOverHttps](INFO): SBD MOMSN=23824518
2024-12-18T23:22:14.758Z,1734564134.758 [Default:A.Wait](INFO): Done Waiting.
2024-12-18T23:22:14.758Z,1734564134.758 [Default:A.Wait] Stopped
2024-12-18T23:22:14.758Z,1734564134.758 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-12-18T23:22:15.162Z,1734564135.162 [Default:CheckIn] Running Loop=1
2024-12-18T23:22:15.162Z,1734564135.162 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-18T23:22:15.162Z,1734564135.162 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-18T23:22:15.620Z,1734564135.620 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-12-18T23:22:15.671Z,1734564135.671 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-12-18T23:23:09.432Z,1734564189.432 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-12-18T23:23:22.319Z,1734564202.319 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-12-18T23:23:37.279Z,1734564217.279 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 600 revolution_per_minute
2024-12-18T23:23:37.283Z,1734564217.283 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2024-12-18T23:23:37.642Z,1734564217.642 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:23:42.844Z,1734564222.844 [ThrusterHE](FAULT): Could not get status:serial timeout
2024-12-18T23:23:43.176Z,1734564223.176 [ThrusterHE](FAULT): getSpeed error serial timeout
2024-12-18T23:23:43.176Z,1734564223.176 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2024-12-18T23:23:43.176Z,1734564223.176 [ThrusterHE] Communications Fault, FailCount= 1
2024-12-18T23:23:43.176Z,1734564223.176 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:23:43.180Z,1734564223.180 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:23:43.251Z,1734564223.251 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:23:43.251Z,1734564223.251 [ThrusterHE](INFO): Powering down
2024-12-18T23:23:44.016Z,1734564224.016 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:23:44.017Z,1734564224.017 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:23:44.036Z,1734564224.036 [CBIT](INFO): Clearing failed state for component ThrusterHE
2024-12-18T23:23:44.036Z,1734564224.036 [ThrusterHE] No Fault, FailCount= 1
2024-12-18T23:23:46.074Z,1734564226.074 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:23:46.075Z,1734564226.075 [ThrusterHE] Communications Fault, FailCount= 2
2024-12-18T23:23:46.075Z,1734564226.075 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:23:46.078Z,1734564226.078 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:23:46.447Z,1734564226.447 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:23:46.447Z,1734564226.447 [ThrusterHE](INFO): Powering down
2024-12-18T23:23:47.266Z,1734564227.266 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:23:47.267Z,1734564227.267 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:23:47.271Z,1734564227.271 [CBIT](INFO): Clearing failed state for component ThrusterHE
2024-12-18T23:23:47.271Z,1734564227.271 [ThrusterHE] No Fault, FailCount= 2
2024-12-18T23:23:49.304Z,1734564229.304 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:23:49.304Z,1734564229.304 [ThrusterHE] Communications Fault, FailCount= 3
2024-12-18T23:23:49.304Z,1734564229.304 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:23:49.312Z,1734564229.312 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:23:49.692Z,1734564229.692 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:23:49.692Z,1734564229.692 [ThrusterHE](INFO): Powering down
2024-12-18T23:23:50.501Z,1734564230.501 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:23:50.501Z,1734564230.501 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:23:50.505Z,1734564230.505 [CBIT](INFO): Clearing failed state for component ThrusterHE
2024-12-18T23:23:50.505Z,1734564230.505 [ThrusterHE] No Fault, FailCount= 3
2024-12-18T23:23:51.277Z,1734564231.277 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-12-18T23:23:52.540Z,1734564232.540 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:23:52.540Z,1734564232.540 [ThrusterHE] Communications Fault, FailCount= 4
2024-12-18T23:23:52.540Z,1734564232.540 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:23:52.543Z,1734564232.543 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:23:52.945Z,1734564232.945 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:23:52.945Z,1734564232.945 [ThrusterHE](INFO): Powering down
2024-12-18T23:23:53.720Z,1734564233.720 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:23:53.721Z,1734564233.721 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:23:53.724Z,1734564233.724 [CBIT](INFO): Clearing failed state for component ThrusterHE
2024-12-18T23:23:53.724Z,1734564233.724 [ThrusterHE] No Fault, FailCount= 4
2024-12-18T23:23:55.756Z,1734564235.756 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:23:55.757Z,1734564235.757 [ThrusterHE] Communications Fault, FailCount= 5
2024-12-18T23:23:55.757Z,1734564235.757 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:23:55.760Z,1734564235.760 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:23:55.761Z,1734564235.761 [CBIT](CRITICAL): Communications Fault in component: ThrusterHE
2024-12-18T23:23:56.162Z,1734564236.162 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:23:56.162Z,1734564236.162 [ThrusterHE](INFO): Powering down
2024-12-18T23:23:56.164Z,1734564236.164 [CommandExec](FAULT): Scheduling is paused
2024-12-18T23:23:56.164Z,1734564236.164 [CBIT](INFO): Critical error at 20241218T232355
2024-12-18T23:23:56.165Z,1734564236.165 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-12-18T23:23:56.950Z,1734564236.950 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:23:56.951Z,1734564236.951 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:23:58.253Z,1734564238.253 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-18T23:23:59.170Z,1734564239.170 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 500 revolution_per_minute
2024-12-18T23:24:02.393Z,1734564242.393 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:24:02.393Z,1734564242.393 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:24:02.394Z,1734564242.394 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:24:05.108Z,1734564245.108 [CommandExec](IMPORTANT): got command failComponent none ThrusterHE
2024-12-18T23:24:05.108Z,1734564245.108 [ThrusterHE] No Fault, FailCount= 5
2024-12-18T23:24:05.108Z,1734564245.108 [CommandExec](IMPORTANT): ThrusterHE failureMode is No Fault
2024-12-18T23:24:05.435Z,1734564245.435 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:24:05.435Z,1734564245.435 [ThrusterHE] Communications Fault, FailCount= 6
2024-12-18T23:24:05.435Z,1734564245.435 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:24:05.439Z,1734564245.439 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:24:05.850Z,1734564245.850 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:24:05.850Z,1734564245.850 [ThrusterHE](INFO): Powering down
2024-12-18T23:24:06.645Z,1734564246.645 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:24:06.646Z,1734564246.646 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:24:12.698Z,1734564252.698 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-12-18T23:24:12.698Z,1734564252.698 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:24:12.748Z,1734564252.748 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:24:13.130Z,1734564253.130 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:24:13.130Z,1734564253.130 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-12-18T23:24:15.913Z,1734564255.913 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:24:21.641Z,1734564261.641 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:24:21.641Z,1734564261.641 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:24:21.641Z,1734564261.641 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:24:24.984Z,1734564264.984 [CommandExec](IMPORTANT): got command failComponent none ThrusterHE
2024-12-18T23:24:24.984Z,1734564264.984 [ThrusterHE] No Fault, FailCount= 6
2024-12-18T23:24:24.984Z,1734564264.984 [CommandExec](IMPORTANT): ThrusterHE failureMode is No Fault
2024-12-18T23:24:25.269Z,1734564265.269 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:24:25.269Z,1734564265.269 [ThrusterHE] Communications Fault, FailCount= 7
2024-12-18T23:24:25.269Z,1734564265.269 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:24:25.272Z,1734564265.272 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:24:25.667Z,1734564265.667 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:24:25.667Z,1734564265.667 [ThrusterHE](INFO): Powering down
2024-12-18T23:24:26.444Z,1734564266.444 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:24:26.445Z,1734564266.445 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:24:29.649Z,1734564269.649 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:24:31.640Z,1734564271.640 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:24:31.641Z,1734564271.641 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:24:31.641Z,1734564271.641 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:24:36.130Z,1734564276.130 [CommandExec](IMPORTANT): got command maintain clear
2024-12-18T23:24:36.149Z,1734564276.149 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:24:38.313Z,1734564278.313 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:24:38.313Z,1734564278.313 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:24:38.313Z,1734564278.313 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:24:41.452Z,1734564281.452 [CommandExec](IMPORTANT): got command failComponent none ThrusterHE
2024-12-18T23:24:41.452Z,1734564281.452 [ThrusterHE] No Fault, FailCount= 7
2024-12-18T23:24:41.452Z,1734564281.452 [CommandExec](IMPORTANT): ThrusterHE failureMode is No Fault
2024-12-18T23:24:41.817Z,1734564281.817 [ThrusterHE](FAULT): Thruster failed to initialize
2024-12-18T23:24:41.817Z,1734564281.817 [ThrusterHE] Communications Fault, FailCount= 8
2024-12-18T23:24:41.817Z,1734564281.817 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:24:41.825Z,1734564281.825 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:24:41.825Z,1734564281.825 [CBIT](CRITICAL): Communications Fault in component: ThrusterHE
2024-12-18T23:24:42.232Z,1734564282.232 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:24:42.233Z,1734564282.233 [ThrusterHE](INFO): Powering down
2024-12-18T23:24:42.235Z,1734564282.235 [CBIT](INFO): Critical error at 20241218T232441
2024-12-18T23:24:43.385Z,1734564283.385 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:24:57.827Z,1734564297.827 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:25:01.653Z,1734564301.653 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300 revolution_per_minute
2024-12-18T23:25:01.655Z,1734564301.655 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2024-12-18T23:25:01.924Z,1734564301.924 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:25:11.563Z,1734564311.563 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:25:12.706Z,1734564312.706 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:25:12.707Z,1734564312.707 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:25:12.707Z,1734564312.707 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:25:19.301Z,1734564319.301 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-18T23:25:22.710Z,1734564322.710 [CommandExec](IMPORTANT): got command failComponent none ThrusterHE
2024-12-18T23:25:22.710Z,1734564322.710 [ThrusterHE] No Fault, FailCount= 8
2024-12-18T23:25:22.711Z,1734564322.711 [CommandExec](IMPORTANT): ThrusterHE failureMode is No Fault
2024-12-18T23:25:22.910Z,1734564322.910 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:25:22.911Z,1734564322.911 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:25:25.704Z,1734564325.704 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:25:28.580Z,1734564328.580 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-12-18T23:25:39.035Z,1734564339.035 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:25:43.827Z,1734564343.827 [CommandExec](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 500 revolution_per_minute
2024-12-18T23:25:47.638Z,1734564347.638 [ThrusterHE](FAULT): Could not get status:serial timeout
2024-12-18T23:25:47.726Z,1734564347.726 [ThrusterHE](FAULT): getSpeed error serial timeout
2024-12-18T23:25:47.726Z,1734564347.726 [ThrusterHE](FAULT): Thruster uart error: serial timeout
2024-12-18T23:25:47.726Z,1734564347.726 [ThrusterHE] Communications Fault, FailCount= 9
2024-12-18T23:25:47.726Z,1734564347.726 [ThrusterHE](ERROR): Communications Fault
2024-12-18T23:25:47.730Z,1734564347.730 [CBIT](ERROR): Communications Fault in component: ThrusterHE
2024-12-18T23:25:47.730Z,1734564347.730 [CBIT](CRITICAL): Communications Fault in component: ThrusterHE
2024-12-18T23:25:47.949Z,1734564347.949 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-12-18T23:25:47.949Z,1734564347.949 [ThrusterHE](INFO): Powering down
2024-12-18T23:25:47.951Z,1734564347.951 [CBIT](INFO): Critical error at 20241218T232547
2024-12-18T23:25:48.750Z,1734564348.750 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-12-18T23:25:48.867Z,1734564348.867 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-12-18T23:25:52.367Z,1734564352.367 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:25:58.826Z,1734564358.826 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:25:58.827Z,1734564358.827 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:25:58.827Z,1734564358.827 [CommandExec](IMPORTANT): ThrusterHE: Communications Fault
2024-12-18T23:26:02.477Z,1734564362.477 [CommandExec](IMPORTANT): got command failComponent none ThrusterHE
2024-12-18T23:26:02.478Z,1734564362.478 [ThrusterHE] No Fault, FailCount= 9
2024-12-18T23:26:02.478Z,1734564362.478 [CommandExec](IMPORTANT): ThrusterHE failureMode is No Fault
2024-12-18T23:26:05.700Z,1734564365.700 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:26:11.861Z,1734564371.861 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-12-18T23:26:17.044Z,1734564377.044 [CommandExec](IMPORTANT): got command maintain clear
2024-12-18T23:26:17.163Z,1734564377.163 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:26:19.435Z,1734564379.435 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-12-18T23:26:33.575Z,1734564393.575 [BPC1](ERROR): BPC1A got IPBS message with 4 sticks (min is 7).
2024-12-18T23:26:40.856Z,1734564400.856 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2024-12-18T23:26:40.856Z,1734564400.856 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2024-12-18T23:26:40.860Z,1734564400.860 [BPC1](INFO): Received data from all battery sticks.
2024-12-18T23:27:13.614Z,1734564433.614 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-12-18T23:27:13.614Z,1734564433.614 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:27:13.637Z,1734564433.637 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:27:14.024Z,1734564434.024 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:27:14.024Z,1734564434.024 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-12-18T23:27:15.215Z,1734564435.215 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-18T23:22:15.2Z
2024-12-18T23:27:15.216Z,1734564435.216 [Default:CheckIn:Read_GPS] Stopped
2024-12-18T23:27:15.216Z,1734564435.216 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-18T23:27:15.624Z,1734564435.624 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-12-18T23:27:16.907Z,1734564436.907 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20241218T231748/Courier0004.lzma
2024-12-18T23:27:17.175Z,1734564437.175 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0004.lzma.bak
2024-12-18T23:27:17.175Z,1734564437.175 [DataOverHttps](INFO): SBD MOMSN=23824542
2024-12-18T23:27:34.622Z,1734564454.622 [DataOverHttps](INFO): Sending 714 bytes from file Logs/20241218T231748/Express0005.lzma
2024-12-18T23:27:35.526Z,1734564455.526 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0005.lzma.bak
2024-12-18T23:27:35.526Z,1734564455.526 [DataOverHttps](INFO): SBD MOMSN=23824551
2024-12-18T23:27:37.899Z,1734564457.899 [Default:CheckIn:Read_Iridium] Stopped
2024-12-18T23:27:37.899Z,1734564457.899 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-18T23:27:37.899Z,1734564457.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:28:49.906Z,1734564529.906 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:28:49.906Z,1734564529.906 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:28:49.906Z,1734564529.906 [CommandExec](IMPORTANT): No failed Components.
2024-12-18T23:28:50.147Z,1734564530.147 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-12-18T23:28:50.147Z,1734564530.147 [NAL9602] Data Fault, FailCount= 1
2024-12-18T23:28:50.148Z,1734564530.148 [NAL9602](ERROR): Data Fault
2024-12-18T23:28:50.205Z,1734564530.205 [CBIT](ERROR): Data Fault in component: NAL9602
2024-12-18T23:28:50.543Z,1734564530.543 [NAL9602](INFO): Powering down
2024-12-18T23:28:51.408Z,1734564531.408 [CBIT](INFO): Clearing failed state for component NAL9602
2024-12-18T23:28:51.408Z,1734564531.408 [NAL9602] No Fault, FailCount= 1
2024-12-18T23:28:53.374Z,1734564533.374 [CommandExec](IMPORTANT): got command failComponent
2024-12-18T23:28:53.374Z,1734564533.374 [CommandExec](IMPORTANT): Failed components:
2024-12-18T23:28:53.374Z,1734564533.374 [CommandExec](IMPORTANT): No failed Components.
2024-12-18T23:29:20.847Z,1734564560.847 [NAL9602](INFO): Powering up NAL9602
2024-12-18T23:29:31.755Z,1734564571.755 [NAL9602](INFO): NAL9602 initialized
2024-12-18T23:30:14.630Z,1734564614.630 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-12-18T23:30:14.630Z,1734564614.630 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:30:14.641Z,1734564614.641 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:30:15.044Z,1734564615.044 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:30:15.044Z,1734564615.044 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-12-18T23:30:18.430Z,1734564618.430 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-12-18T23:32:38.503Z,1734564758.503 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-18T23:32:38.504Z,1734564758.504 [Default:CheckIn:C.Wait] Stopped
2024-12-18T23:32:38.504Z,1734564758.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-18T23:32:38.504Z,1734564758.504 [Default:CheckIn:D] Running Loop=1
2024-12-18T23:32:38.908Z,1734564758.908 [Default:CheckIn:D] Stopped
2024-12-18T23:32:38.908Z,1734564758.908 [Default:CheckIn:E] Running Loop=1
2024-12-18T23:32:39.332Z,1734564759.332 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.624514 min
2024-12-18T23:32:39.332Z,1734564759.332 [Default:CheckIn:E] Stopped
2024-12-18T23:32:39.332Z,1734564759.332 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-18T23:32:39.332Z,1734564759.332 [Default:CheckIn] Stopped
2024-12-18T23:32:39.332Z,1734564759.332 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-18T23:32:39.333Z,1734564759.333 [Default:CheckIn](INFO): Running loop #2
2024-12-18T23:32:39.333Z,1734564759.333 [Default:CheckIn] Running Loop=2
2024-12-18T23:32:39.333Z,1734564759.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-18T23:32:39.333Z,1734564759.333 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-18T23:33:15.674Z,1734564795.674 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-12-18T23:33:15.674Z,1734564795.674 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:33:15.685Z,1734564795.685 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:33:16.088Z,1734564796.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:33:16.088Z,1734564796.088 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-12-18T23:36:16.678Z,1734564976.678 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-12-18T23:36:16.678Z,1734564976.678 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-12-18T23:36:16.693Z,1734564976.693 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-12-18T23:36:17.107Z,1734564977.107 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-12-18T23:36:17.107Z,1734564977.107 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-12-18T23:37:39.512Z,1734565059.512 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-12-18T23:32:39.3Z
2024-12-18T23:37:39.512Z,1734565059.512 [Default:CheckIn:Read_GPS] Stopped
2024-12-18T23:37:39.512Z,1734565059.512 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-18T23:37:46.797Z,1734565066.797 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241218T231748/Courier0007.lzma
2024-12-18T23:37:47.799Z,1734565067.799 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0007.lzma.bak
2024-12-18T23:37:47.800Z,1734565067.800 [DataOverHttps](INFO): SBD MOMSN=23824625
2024-12-18T23:38:05.049Z,1734565085.049 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20241218T231748/Express0008.lzma
2024-12-18T23:38:06.051Z,1734565086.051 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0008.lzma.bak
2024-12-18T23:38:06.051Z,1734565086.051 [DataOverHttps](INFO): SBD MOMSN=23824631
2024-12-18T23:38:09.004Z,1734565089.004 [Default:CheckIn:Read_Iridium] Stopped
2024-12-18T23:38:09.004Z,1734565089.004 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-18T23:38:09.004Z,1734565089.004 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:38:09.790Z,1734565089.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233806.00,A,3648.15598,N,12147.27833,W,0.505,322.36,181224,,,A*7F
2024-12-18T23:38:09.794Z,1734565089.794 [NAL9602](INFO): GPS fix at 20241218T233806: (36.802600, -121.787972)
2024-12-18T23:38:42.522Z,1734565122.522 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-18T23:39:04.694Z,1734565144.694 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position
2024-12-18T23:39:04.802Z,1734565144.802 [Reporter](INFO): platform_buoyancy_position 900.740051 cc
2024-12-18T23:39:16.519Z,1734565156.519 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 100 cubic_centimeter
2024-12-18T23:39:16.530Z,1734565156.530 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2024-12-18T23:39:16.909Z,1734565156.909 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:39:19.764Z,1734565159.764 [Reporter](INFO): platform_buoyancy_position 898.591248 cc
2024-12-18T23:39:20.187Z,1734565160.187 [Reporter](INFO): platform_buoyancy_position 889.861755 cc
2024-12-18T23:39:20.595Z,1734565160.595 [Reporter](INFO): platform_buoyancy_position 887.847229 cc
2024-12-18T23:39:20.868Z,1734565160.868 [CommandExec](IMPORTANT): got command get platform_roll_angle degree
2024-12-18T23:39:20.869Z,1734565160.869 [CommandExec](IMPORTANT): platform_roll_angle 17.881329 arcdeg
2024-12-18T23:39:20.000Z,1734565161.000 [Reporter](INFO): platform_buoyancy_position 885.429871 cc
2024-12-18T23:39:21.388Z,1734565161.388 [Reporter](INFO): platform_buoyancy_position 881.266541 cc
2024-12-18T23:39:21.794Z,1734565161.794 [Reporter](INFO): platform_buoyancy_position 876.297424 cc
2024-12-18T23:39:22.214Z,1734565162.214 [Reporter](INFO): platform_buoyancy_position 870.656860 cc
2024-12-18T23:39:22.628Z,1734565162.628 [Reporter](INFO): platform_buoyancy_position 866.359253 cc
2024-12-18T23:39:23.012Z,1734565163.012 [Reporter](INFO): platform_buoyancy_position 861.927368 cc
2024-12-18T23:39:23.432Z,1734565163.432 [Reporter](INFO): platform_buoyancy_position 855.480957 cc
2024-12-18T23:39:23.842Z,1734565163.842 [Reporter](INFO): platform_buoyancy_position 851.183350 cc
2024-12-18T23:39:24.220Z,1734565164.220 [Reporter](INFO): platform_buoyancy_position 844.871216 cc
2024-12-18T23:39:24.631Z,1734565164.631 [Reporter](INFO): platform_buoyancy_position 838.693420 cc
2024-12-18T23:39:25.040Z,1734565165.040 [Reporter](INFO): platform_buoyancy_position 834.127258 cc
2024-12-18T23:39:25.441Z,1734565165.441 [Reporter](INFO): platform_buoyancy_position 829.695312 cc
2024-12-18T23:39:25.835Z,1734565165.835 [Reporter](INFO): platform_buoyancy_position 825.397766 cc
2024-12-18T23:39:26.228Z,1734565166.228 [Reporter](INFO): platform_buoyancy_position 819.085632 cc
2024-12-18T23:39:26.632Z,1734565166.632 [Reporter](INFO): platform_buoyancy_position 814.385132 cc
2024-12-18T23:39:27.047Z,1734565167.047 [Reporter](INFO): platform_buoyancy_position 808.341614 cc
2024-12-18T23:39:27.452Z,1734565167.452 [Reporter](INFO): platform_buoyancy_position 803.641113 cc
2024-12-18T23:39:27.832Z,1734565167.832 [Reporter](INFO): platform_buoyancy_position 797.597656 cc
2024-12-18T23:39:28.247Z,1734565168.247 [Reporter](INFO): platform_buoyancy_position 793.031433 cc
2024-12-18T23:39:28.652Z,1734565168.652 [Reporter](INFO): platform_buoyancy_position 786.853638 cc
2024-12-18T23:39:29.080Z,1734565169.080 [Reporter](INFO): platform_buoyancy_position 780.407227 cc
2024-12-18T23:39:29.448Z,1734565169.448 [Reporter](INFO): platform_buoyancy_position 776.109619 cc
2024-12-18T23:39:29.860Z,1734565169.860 [Reporter](INFO): platform_buoyancy_position 771.677734 cc
2024-12-18T23:39:30.276Z,1734565170.276 [Reporter](INFO): platform_buoyancy_position 765.231323 cc
2024-12-18T23:39:30.683Z,1734565170.683 [Reporter](INFO): platform_buoyancy_position 760.933716 cc
2024-12-18T23:39:31.088Z,1734565171.088 [Reporter](INFO): platform_buoyancy_position 754.621643 cc
2024-12-18T23:39:31.468Z,1734565171.468 [Reporter](INFO): platform_buoyancy_position 750.189758 cc
2024-12-18T23:39:31.888Z,1734565171.888 [Reporter](INFO): platform_buoyancy_position 745.623535 cc
2024-12-18T23:39:32.304Z,1734565172.304 [Reporter](INFO): platform_buoyancy_position 739.580017 cc
2024-12-18T23:39:32.708Z,1734565172.708 [Reporter](INFO): platform_buoyancy_position 735.148132 cc
2024-12-18T23:39:33.084Z,1734565173.084 [Reporter](INFO): platform_buoyancy_position 728.970337 cc
2024-12-18T23:39:33.500Z,1734565173.500 [Reporter](INFO): platform_buoyancy_position 724.538452 cc
2024-12-18T23:39:33.919Z,1734565173.919 [Reporter](INFO): platform_buoyancy_position 718.092041 cc
2024-12-18T23:39:34.328Z,1734565174.328 [Reporter](INFO): platform_buoyancy_position 713.660156 cc
2024-12-18T23:39:34.709Z,1734565174.709 [Reporter](INFO): platform_buoyancy_position 708.959656 cc
2024-12-18T23:39:35.120Z,1734565175.120 [Reporter](INFO): platform_buoyancy_position 702.916138 cc
2024-12-18T23:39:35.512Z,1734565175.512 [Reporter](INFO): platform_buoyancy_position 698.484253 cc
2024-12-18T23:39:35.938Z,1734565175.938 [Reporter](INFO): platform_buoyancy_position 692.306458 cc
2024-12-18T23:39:36.316Z,1734565176.316 [Reporter](INFO): platform_buoyancy_position 687.874512 cc
2024-12-18T23:39:36.728Z,1734565176.728 [Reporter](INFO): platform_buoyancy_position 681.562439 cc
2024-12-18T23:39:37.136Z,1734565177.136 [Reporter](INFO): platform_buoyancy_position 676.861938 cc
2024-12-18T23:39:37.540Z,1734565177.540 [Reporter](INFO): platform_buoyancy_position 670.818420 cc
2024-12-18T23:39:37.961Z,1734565177.961 [Reporter](INFO): platform_buoyancy_position 665.983643 cc
2024-12-18T23:39:38.391Z,1734565178.391 [Reporter](INFO): platform_buoyancy_position 659.940125 cc
2024-12-18T23:39:38.767Z,1734565178.767 [Reporter](INFO): platform_buoyancy_position 655.642517 cc
2024-12-18T23:39:39.176Z,1734565179.176 [Reporter](INFO): platform_buoyancy_position 649.330444 cc
2024-12-18T23:39:39.576Z,1734565179.576 [Reporter](INFO): platform_buoyancy_position 644.898499 cc
2024-12-18T23:39:39.972Z,1734565179.972 [Reporter](INFO): platform_buoyancy_position 638.720703 cc
2024-12-18T23:39:40.381Z,1734565180.381 [Reporter](INFO): platform_buoyancy_position 634.288818 cc
2024-12-18T23:39:40.779Z,1734565180.779 [Reporter](INFO): platform_buoyancy_position 627.842407 cc
2024-12-18T23:39:41.195Z,1734565181.195 [Reporter](INFO): platform_buoyancy_position 623.410522 cc
2024-12-18T23:39:41.588Z,1734565181.588 [Reporter](INFO): platform_buoyancy_position 618.038513 cc
2024-12-18T23:39:41.976Z,1734565181.976 [Reporter](INFO): platform_buoyancy_position 612.800842 cc
2024-12-18T23:39:42.400Z,1734565182.400 [Reporter](INFO): platform_buoyancy_position 608.368896 cc
2024-12-18T23:39:42.804Z,1734565182.804 [Reporter](INFO): platform_buoyancy_position 602.056824 cc
2024-12-18T23:39:43.192Z,1734565183.192 [Reporter](INFO): platform_buoyancy_position 597.624939 cc
2024-12-18T23:39:43.592Z,1734565183.592 [Reporter](INFO): platform_buoyancy_position 591.312805 cc
2024-12-18T23:39:44.015Z,1734565184.015 [Reporter](INFO): platform_buoyancy_position 586.880920 cc
2024-12-18T23:39:44.425Z,1734565184.425 [Reporter](INFO): platform_buoyancy_position 580.434509 cc
2024-12-18T23:39:44.813Z,1734565184.813 [Reporter](INFO): platform_buoyancy_position 576.136902 cc
2024-12-18T23:39:45.216Z,1734565185.216 [Reporter](INFO): platform_buoyancy_position 569.824829 cc
2024-12-18T23:39:45.637Z,1734565185.637 [Reporter](INFO): platform_buoyancy_position 565.392944 cc
2024-12-18T23:39:46.028Z,1734565186.028 [Reporter](INFO): platform_buoyancy_position 559.080811 cc
2024-12-18T23:39:46.424Z,1734565186.424 [Reporter](INFO): platform_buoyancy_position 554.783203 cc
2024-12-18T23:39:46.865Z,1734565186.865 [Reporter](INFO): platform_buoyancy_position 548.471130 cc
2024-12-18T23:39:47.244Z,1734565187.244 [Reporter](INFO): platform_buoyancy_position 543.904907 cc
2024-12-18T23:39:47.662Z,1734565187.662 [Reporter](INFO): platform_buoyancy_position 537.592834 cc
2024-12-18T23:39:48.056Z,1734565188.056 [Reporter](INFO): platform_buoyancy_position 532.892334 cc
2024-12-18T23:39:48.452Z,1734565188.452 [Reporter](INFO): platform_buoyancy_position 526.983093 cc
2024-12-18T23:39:48.863Z,1734565188.863 [Reporter](INFO): platform_buoyancy_position 522.416931 cc
2024-12-18T23:39:49.260Z,1734565189.260 [Reporter](INFO): platform_buoyancy_position 516.104797 cc
2024-12-18T23:39:49.676Z,1734565189.676 [Reporter](INFO): platform_buoyancy_position 511.672943 cc
2024-12-18T23:39:50.061Z,1734565190.061 [Reporter](INFO): platform_buoyancy_position 505.360901 cc
2024-12-18T23:39:50.484Z,1734565190.484 [Reporter](INFO): platform_buoyancy_position 500.928894 cc
2024-12-18T23:39:50.896Z,1734565190.896 [Reporter](INFO): platform_buoyancy_position 494.482574 cc
2024-12-18T23:39:51.293Z,1734565191.293 [Reporter](INFO): platform_buoyancy_position 490.184998 cc
2024-12-18T23:39:51.684Z,1734565191.684 [Reporter](INFO): platform_buoyancy_position 485.618744 cc
2024-12-18T23:39:52.100Z,1734565192.100 [Reporter](INFO): platform_buoyancy_position 479.440948 cc
2024-12-18T23:39:52.492Z,1734565192.492 [Reporter](INFO): platform_buoyancy_position 475.143341 cc
2024-12-18T23:39:52.924Z,1734565192.924 [Reporter](INFO): platform_buoyancy_position 468.831268 cc
2024-12-18T23:39:53.353Z,1734565193.353 [Reporter](INFO): platform_buoyancy_position 462.922028 cc
2024-12-18T23:39:53.712Z,1734565193.712 [Reporter](INFO): platform_buoyancy_position 458.087250 cc
2024-12-18T23:39:54.132Z,1734565194.132 [Reporter](INFO): platform_buoyancy_position 453.655365 cc
2024-12-18T23:39:54.544Z,1734565194.544 [Reporter](INFO): platform_buoyancy_position 449.357758 cc
2024-12-18T23:39:54.945Z,1734565194.945 [Reporter](INFO): platform_buoyancy_position 442.911346 cc
2024-12-18T23:39:55.332Z,1734565195.332 [Reporter](INFO): platform_buoyancy_position 438.613739 cc
2024-12-18T23:39:55.760Z,1734565195.760 [Reporter](INFO): platform_buoyancy_position 432.167328 cc
2024-12-18T23:39:56.165Z,1734565196.165 [Reporter](INFO): platform_buoyancy_position 427.869720 cc
2024-12-18T23:39:56.574Z,1734565196.574 [Reporter](INFO): platform_buoyancy_position 421.423370 cc
2024-12-18T23:39:56.952Z,1734565196.952 [Reporter](INFO): platform_buoyancy_position 417.260040 cc
2024-12-18T23:39:57.360Z,1734565197.360 [Reporter](INFO): platform_buoyancy_position 410.679352 cc
2024-12-18T23:39:57.740Z,1734565197.740 [Reporter](INFO): platform_buoyancy_position 406.516052 cc
2024-12-18T23:39:58.156Z,1734565198.156 [Reporter](INFO): platform_buoyancy_position 401.949829 cc
2024-12-18T23:39:58.551Z,1734565198.551 [Reporter](INFO): platform_buoyancy_position 395.637726 cc
2024-12-18T23:39:58.976Z,1734565198.976 [Reporter](INFO): platform_buoyancy_position 389.325653 cc
2024-12-18T23:39:59.354Z,1734565199.354 [Reporter](INFO): platform_buoyancy_position 385.028046 cc
2024-12-18T23:39:59.772Z,1734565199.772 [Reporter](INFO): platform_buoyancy_position 380.730438 cc
2024-12-18T23:40:00.172Z,1734565200.172 [Reporter](INFO): platform_buoyancy_position 374.149750 cc
2024-12-18T23:40:00.591Z,1734565200.591 [Reporter](INFO): platform_buoyancy_position 369.583527 cc
2024-12-18T23:40:00.976Z,1734565200.976 [Reporter](INFO): platform_buoyancy_position 363.405731 cc
2024-12-18T23:40:01.395Z,1734565201.395 [Reporter](INFO): platform_buoyancy_position 356.959351 cc
2024-12-18T23:40:01.811Z,1734565201.811 [Reporter](INFO): platform_buoyancy_position 351.453033 cc
2024-12-18T23:40:02.184Z,1734565202.184 [Reporter](INFO): platform_buoyancy_position 346.349640 cc
2024-12-18T23:40:02.595Z,1734565202.595 [Reporter](INFO): platform_buoyancy_position 340.843323 cc
2024-12-18T23:40:03.007Z,1734565203.007 [Reporter](INFO): platform_buoyancy_position 335.471344 cc
2024-12-18T23:40:03.416Z,1734565203.416 [Reporter](INFO): platform_buoyancy_position 329.024933 cc
2024-12-18T23:40:03.797Z,1734565203.797 [Reporter](INFO): platform_buoyancy_position 324.727325 cc
2024-12-18T23:40:04.204Z,1734565204.204 [Reporter](INFO): platform_buoyancy_position 318.415222 cc
2024-12-18T23:40:04.616Z,1734565204.616 [Reporter](INFO): platform_buoyancy_position 313.983337 cc
2024-12-18T23:40:05.032Z,1734565205.032 [Reporter](INFO): platform_buoyancy_position 307.671234 cc
2024-12-18T23:40:05.412Z,1734565205.412 [Reporter](INFO): platform_buoyancy_position 303.239319 cc
2024-12-18T23:40:05.823Z,1734565205.823 [Reporter](INFO): platform_buoyancy_position 298.270233 cc
2024-12-18T23:40:06.253Z,1734565206.253 [Reporter](INFO): platform_buoyancy_position 292.629639 cc
2024-12-18T23:40:06.648Z,1734565206.648 [Reporter](INFO): platform_buoyancy_position 286.989044 cc
2024-12-18T23:40:07.026Z,1734565207.026 [Reporter](INFO): platform_buoyancy_position 281.751343 cc
2024-12-18T23:40:07.451Z,1734565207.451 [Reporter](INFO): platform_buoyancy_position 277.319427 cc
2024-12-18T23:40:07.865Z,1734565207.865 [Reporter](INFO): platform_buoyancy_position 271.007324 cc
2024-12-18T23:40:08.316Z,1734565208.316 [Reporter](INFO): platform_buoyancy_position 264.829529 cc
2024-12-18T23:40:08.652Z,1734565208.652 [Reporter](INFO): platform_buoyancy_position 260.397644 cc
2024-12-18T23:40:09.064Z,1734565209.064 [Reporter](INFO): platform_buoyancy_position 256.100037 cc
2024-12-18T23:40:09.485Z,1734565209.485 [Reporter](INFO): platform_buoyancy_position 251.668121 cc
2024-12-18T23:40:09.892Z,1734565209.892 [Reporter](INFO): platform_buoyancy_position 245.356033 cc
2024-12-18T23:40:10.272Z,1734565210.272 [Reporter](INFO): platform_buoyancy_position 241.058441 cc
2024-12-18T23:40:10.691Z,1734565210.691 [Reporter](INFO): platform_buoyancy_position 236.492218 cc
2024-12-18T23:40:11.084Z,1734565211.084 [Reporter](INFO): platform_buoyancy_position 230.180115 cc
2024-12-18T23:40:11.491Z,1734565211.491 [Reporter](INFO): platform_buoyancy_position 225.882538 cc
2024-12-18T23:40:11.904Z,1734565211.904 [Reporter](INFO): platform_buoyancy_position 219.704742 cc
2024-12-18T23:40:12.310Z,1734565212.310 [Reporter](INFO): platform_buoyancy_position 215.272827 cc
2024-12-18T23:40:12.696Z,1734565212.696 [Reporter](INFO): platform_buoyancy_position 210.840912 cc
2024-12-18T23:40:13.103Z,1734565213.103 [Reporter](INFO): platform_buoyancy_position 204.528839 cc
2024-12-18T23:40:13.504Z,1734565213.504 [Reporter](INFO): platform_buoyancy_position 199.828339 cc
2024-12-18T23:40:13.896Z,1734565213.896 [Reporter](INFO): platform_buoyancy_position 193.784821 cc
2024-12-18T23:40:14.304Z,1734565214.304 [Reporter](INFO): platform_buoyancy_position 189.352936 cc
2024-12-18T23:40:14.720Z,1734565214.720 [Reporter](INFO): platform_buoyancy_position 183.040833 cc
2024-12-18T23:40:15.116Z,1734565215.116 [Reporter](INFO): platform_buoyancy_position 178.608917 cc
2024-12-18T23:40:15.540Z,1734565215.540 [Reporter](INFO): platform_buoyancy_position 174.311310 cc
2024-12-18T23:40:15.916Z,1734565215.916 [Reporter](INFO): platform_buoyancy_position 167.864929 cc
2024-12-18T23:40:16.335Z,1734565216.335 [Reporter](INFO): platform_buoyancy_position 161.418518 cc
2024-12-18T23:40:16.744Z,1734565216.744 [Reporter](INFO): platform_buoyancy_position 154.972107 cc
2024-12-18T23:40:17.148Z,1734565217.148 [Reporter](INFO): platform_buoyancy_position 150.674530 cc
2024-12-18T23:40:17.536Z,1734565217.536 [Reporter](INFO): platform_buoyancy_position 146.376923 cc
2024-12-18T23:40:17.955Z,1734565217.955 [Reporter](INFO): platform_buoyancy_position 137.916016 cc
2024-12-18T23:40:18.367Z,1734565218.367 [Reporter](INFO): platform_buoyancy_position 133.618408 cc
2024-12-18T23:40:18.768Z,1734565218.768 [Reporter](INFO): platform_buoyancy_position 129.320801 cc
2024-12-18T23:40:19.161Z,1734565219.161 [Reporter](INFO): platform_buoyancy_position 124.888901 cc
2024-12-18T23:40:19.576Z,1734565219.576 [Reporter](INFO): platform_buoyancy_position 120.457008 cc
2024-12-18T23:40:19.984Z,1734565219.984 [Reporter](INFO): platform_buoyancy_position 113.339119 cc
2024-12-18T23:40:20.541Z,1734565220.541 [Reporter](INFO): platform_buoyancy_position 109.847313 cc
2024-12-18T23:40:45.097Z,1734565245.097 [CommandExec](IMPORTANT): got command get platform_mass_position
2024-12-18T23:40:45.097Z,1734565245.097 [CommandExec](IMPORTANT): platform_mass_position 0.008563 m
2024-12-18T23:40:50.529Z,1734565250.529 [CommandExec](IMPORTANT): got command show variable massDefault
2024-12-18T23:40:50.666Z,1734565250.666 [CommandExec](IMPORTANT): VerticalControl.massDefault (centimeter)
2024-12-18T23:40:59.055Z,1734565259.055 [CommandExec](IMPORTANT): got command get VerticalControl.massDefault
2024-12-18T23:40:59.055Z,1734565259.055 [CommandExec](IMPORTANT): VerticalControl.massDefault 0.856226 cm
2024-12-18T23:41:19.791Z,1734565279.791 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.2 centimeter persist
2024-12-18T23:41:45.916Z,1734565305.916 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 50 cubic_centimeter
2024-12-18T23:41:48.161Z,1734565308.161 [Reporter](INFO): platform_buoyancy_position 99.237617 cc
2024-12-18T23:41:48.444Z,1734565308.444 [Reporter](INFO): platform_buoyancy_position 99.774818 cc
2024-12-18T23:41:48.856Z,1734565308.856 [Reporter](INFO): platform_buoyancy_position 99.103310 cc
2024-12-18T23:41:49.276Z,1734565309.276 [Reporter](INFO): platform_buoyancy_position 94.805717 cc
2024-12-18T23:41:49.673Z,1734565309.673 [Reporter](INFO): platform_buoyancy_position 90.508110 cc
2024-12-18T23:41:50.056Z,1734565310.056 [Reporter](INFO): platform_buoyancy_position 86.210503 cc
2024-12-18T23:41:50.476Z,1734565310.476 [Reporter](INFO): platform_buoyancy_position 81.912910 cc
2024-12-18T23:41:50.889Z,1734565310.889 [Reporter](INFO): platform_buoyancy_position 75.600807 cc
2024-12-18T23:41:51.272Z,1734565311.272 [Reporter](INFO): platform_buoyancy_position 71.168907 cc
2024-12-18T23:41:51.724Z,1734565311.724 [Reporter](INFO): platform_buoyancy_position 64.856804 cc
2024-12-18T23:41:52.184Z,1734565312.184 [Reporter](INFO): platform_buoyancy_position 60.424904 cc
2024-12-18T23:41:52.605Z,1734565312.605 [Reporter](INFO): platform_buoyancy_position 56.127312 cc
2024-12-18T23:42:05.487Z,1734565325.487 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 2 centimeter persist
2024-12-18T23:43:01.244Z,1734565381.244 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.5 centimeter persist
2024-12-18T23:43:09.654Z,1734565389.654 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-18T23:43:09.654Z,1734565389.654 [Default:CheckIn:C.Wait] Stopped
2024-12-18T23:43:09.655Z,1734565389.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-18T23:43:09.655Z,1734565389.655 [Default:CheckIn:D] Running Loop=1
2024-12-18T23:43:10.062Z,1734565390.062 [Default:CheckIn:D] Stopped
2024-12-18T23:43:10.062Z,1734565390.062 [Default:CheckIn:E] Running Loop=1
2024-12-18T23:43:10.445Z,1734565390.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.143396 min
2024-12-18T23:43:10.445Z,1734565390.445 [Default:CheckIn:E] Stopped
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn] Stopped
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn](INFO): Running loop #3
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn] Running Loop=3
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-18T23:43:10.446Z,1734565390.446 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-18T23:43:12.448Z,1734565392.448 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234308.00,A,3648.16221,N,12147.27875,W,0.719,137.67,181224,,,A*74
2024-12-18T23:43:12.451Z,1734565392.451 [NAL9602](INFO): GPS fix at 20241218T234308: (36.802703, -121.787979)
2024-12-18T23:43:12.495Z,1734565392.495 [Default:CheckIn:Read_GPS] Stopped
2024-12-18T23:43:12.495Z,1734565392.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-18T23:43:20.374Z,1734565400.374 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20241218T231748/Courier0010.lzma
2024-12-18T23:43:21.375Z,1734565401.375 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0010.lzma.bak
2024-12-18T23:43:21.375Z,1734565401.375 [DataOverHttps](INFO): SBD MOMSN=23824654
2024-12-18T23:43:40.090Z,1734565420.090 [DataOverHttps](INFO): Sending 558 bytes from file Logs/20241218T231748/Express0011.lzma
2024-12-18T23:43:41.092Z,1734565421.092 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0011.lzma.bak
2024-12-18T23:43:41.093Z,1734565421.093 [DataOverHttps](INFO): SBD MOMSN=23824657
2024-12-18T23:43:44.185Z,1734565424.185 [Default:CheckIn:Read_Iridium] Stopped
2024-12-18T23:43:44.185Z,1734565424.185 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-18T23:43:44.185Z,1734565424.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:44:28.278Z,1734565468.278 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1.8 centimeter persist
2024-12-18T23:44:34.286Z,1734565474.286 [NAL9602](INFO): SBD MO Status=2, MOMSN=11428, MT Status=2, MTMSN=0
2024-12-18T23:44:34.287Z,1734565474.287 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:44:50.844Z,1734565490.844 [NAL9602](INFO): SBD MO Status=2, MOMSN=11428, MT Status=2, MTMSN=0
2024-12-18T23:44:50.844Z,1734565490.844 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:45:15.487Z,1734565515.487 [NAL9602](INFO): SBD MO Status=2, MOMSN=11428, MT Status=2, MTMSN=0
2024-12-18T23:45:15.487Z,1734565515.487 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:45:54.959Z,1734565554.959 [CommandExec](IMPORTANT): got command maintain clear
2024-12-18T23:45:55.121Z,1734565555.121 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2024-12-18T23:45:57.664Z,1734565557.664 [Reporter](INFO): platform_buoyancy_position 49.680901 cc
2024-12-18T23:45:58.772Z,1734565558.772 [Reporter](INFO): platform_buoyancy_position 51.829704 cc
2024-12-18T23:45:59.193Z,1734565559.193 [Reporter](INFO): platform_buoyancy_position 56.127312 cc
2024-12-18T23:45:59.579Z,1734565559.579 [Reporter](INFO): platform_buoyancy_position 60.424904 cc
2024-12-18T23:45:59.992Z,1734565559.992 [Reporter](INFO): platform_buoyancy_position 67.005608 cc
2024-12-18T23:46:00.412Z,1734565560.412 [Reporter](INFO): platform_buoyancy_position 71.168907 cc
2024-12-18T23:46:00.835Z,1734565560.835 [Reporter](INFO): platform_buoyancy_position 77.615303 cc
2024-12-18T23:46:01.207Z,1734565561.207 [Reporter](INFO): platform_buoyancy_position 81.778603 cc
2024-12-18T23:46:01.239Z,1734565561.239 [CommandExec](IMPORTANT): got command report clear
2024-12-18T23:48:14.467Z,1734565694.467 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-12-18T23:48:44.775Z,1734565724.775 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-18T23:48:44.775Z,1734565724.775 [Default:CheckIn:C.Wait] Stopped
2024-12-18T23:48:44.775Z,1734565724.775 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-18T23:48:44.775Z,1734565724.775 [Default:CheckIn:D] Running Loop=1
2024-12-18T23:48:45.174Z,1734565725.174 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-18T23:48:45.186Z,1734565725.186 [Default:CheckIn:D] Stopped
2024-12-18T23:48:45.186Z,1734565725.186 [Default:CheckIn:E] Running Loop=1
2024-12-18T23:48:45.596Z,1734565725.596 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 26.729142 min
2024-12-18T23:48:45.596Z,1734565725.596 [Default:CheckIn:E] Stopped
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn] Stopped
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn](INFO): Running loop #4
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn] Running Loop=4
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-18T23:48:45.597Z,1734565725.597 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-18T23:48:47.597Z,1734565727.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,234846.00,A,3648.16346,N,12147.28148,W,0.156,201.98,181224,,,A*76
2024-12-18T23:48:47.599Z,1734565727.599 [NAL9602](INFO): GPS fix at 20241218T234846: (36.802724, -121.788025)
2024-12-18T23:48:47.639Z,1734565727.639 [Default:CheckIn:Read_GPS] Stopped
2024-12-18T23:48:47.640Z,1734565727.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-18T23:48:55.666Z,1734565735.666 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241218T231748/Courier0013.lzma
2024-12-18T23:48:56.667Z,1734565736.667 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0013.lzma.bak
2024-12-18T23:48:56.667Z,1734565736.667 [DataOverHttps](INFO): SBD MOMSN=23824687
2024-12-18T23:49:18.355Z,1734565758.355 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-12-18T23:49:18.437Z,1734565758.437 [NAL9602](ERROR): received:
+CSQ:0
OK428, 2, 0, 0, 0
OK
2024-12-18T23:49:19.905Z,1734565759.905 [DataOverHttps](INFO): Sending 312 bytes from file Logs/20241218T231748/Express0014.lzma
2024-12-18T23:49:20.912Z,1734565760.912 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0014.lzma.bak
2024-12-18T23:49:20.912Z,1734565760.912 [DataOverHttps](INFO): SBD MOMSN=23824691
2024-12-18T23:49:23.662Z,1734565763.662 [Default:CheckIn:Read_Iridium] Stopped
2024-12-18T23:49:23.663Z,1734565763.663 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-18T23:49:23.663Z,1734565763.663 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:51:00.172Z,1734565860.172 [NAL9602](INFO): SBD MO Status=2, MOMSN=11428, MT Status=2, MTMSN=0
2024-12-18T23:51:00.172Z,1734565860.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:51:36.997Z,1734565896.997 [NAL9602](INFO): SBD MO Status=2, MOMSN=11428, MT Status=2, MTMSN=0
2024-12-18T23:51:36.998Z,1734565896.998 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:52:03.269Z,1734565923.269 [NAL9602](INFO): SBD MO Status=0, MOMSN=11428, MT Status=0, MTMSN=0
2024-12-18T23:52:03.269Z,1734565923.269 [NAL9602](INFO): No messages in MT queue
2024-12-18T23:52:33.966Z,1734565953.966 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-18T23:54:24.360Z,1734566064.360 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-18T23:54:24.360Z,1734566064.360 [Default:CheckIn:C.Wait] Stopped
2024-12-18T23:54:24.360Z,1734566064.360 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-18T23:54:24.360Z,1734566064.360 [Default:CheckIn:D] Running Loop=1
2024-12-18T23:54:24.734Z,1734566064.734 [Default:CheckIn:D] Stopped
2024-12-18T23:54:24.735Z,1734566064.735 [Default:CheckIn:E] Running Loop=1
2024-12-18T23:54:25.157Z,1734566065.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.388281 min
2024-12-18T23:54:25.157Z,1734566065.157 [Default:CheckIn:E] Stopped
2024-12-18T23:54:25.157Z,1734566065.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-18T23:54:25.157Z,1734566065.157 [Default:CheckIn] Stopped
2024-12-18T23:54:25.157Z,1734566065.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-18T23:54:25.158Z,1734566065.158 [Default:CheckIn](INFO): Running loop #5
2024-12-18T23:54:25.158Z,1734566065.158 [Default:CheckIn] Running Loop=5
2024-12-18T23:54:25.158Z,1734566065.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-18T23:54:25.158Z,1734566065.158 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-18T23:54:27.140Z,1734566067.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,235426.00,A,3648.13492,N,12147.21081,W,0.039,79.55,181224,,,A*4F
2024-12-18T23:54:27.143Z,1734566067.143 [NAL9602](INFO): GPS fix at 20241218T235426: (36.802249, -121.786847)
2024-12-18T23:54:27.154Z,1734566067.154 [Default:CheckIn:Read_GPS] Stopped
2024-12-18T23:54:27.154Z,1734566067.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-18T23:54:35.257Z,1734566075.257 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0016.lzma
2024-12-18T23:54:36.260Z,1734566076.260 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0016.lzma.bak
2024-12-18T23:54:36.260Z,1734566076.260 [DataOverHttps](INFO): SBD MOMSN=23824758
2024-12-18T23:54:46.212Z,1734566086.212 [NAL9602](INFO): SBD MO Status=2, MOMSN=11429, MT Status=2, MTMSN=0
2024-12-18T23:54:46.212Z,1734566086.212 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:54:55.553Z,1734566095.553 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20241218T231748/Express0017.lzma
2024-12-18T23:54:56.555Z,1734566096.555 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0017.lzma.bak
2024-12-18T23:54:56.555Z,1734566096.555 [DataOverHttps](INFO): SBD MOMSN=23824761
2024-12-18T23:55:00.760Z,1734566100.760 [Default:CheckIn:Read_Iridium] Stopped
2024-12-18T23:55:00.760Z,1734566100.760 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-18T23:55:00.760Z,1734566100.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-18T23:55:04.412Z,1734566104.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=11429, MT Status=2, MTMSN=0
2024-12-18T23:55:04.412Z,1734566104.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:55:26.640Z,1734566126.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=11429, MT Status=2, MTMSN=0
2024-12-18T23:55:26.640Z,1734566126.640 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:55:56.139Z,1734566156.139 [NAL9602](INFO): SBD MO Status=2, MOMSN=11429, MT Status=2, MTMSN=0
2024-12-18T23:55:56.139Z,1734566156.139 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-18T23:56:15.945Z,1734566175.945 [NAL9602](INFO): SBD MO Status=0, MOMSN=11429, MT Status=0, MTMSN=0
2024-12-18T23:56:15.945Z,1734566175.945 [NAL9602](INFO): No messages in MT queue
2024-12-18T23:56:46.651Z,1734566206.651 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:00:01.437Z,1734566401.437 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:00:01.437Z,1734566401.437 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:00:01.437Z,1734566401.437 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:00:01.437Z,1734566401.437 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:00:01.850Z,1734566401.850 [Default:CheckIn:D] Stopped
2024-12-19T00:00:01.850Z,1734566401.850 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.006877 min
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn:E] Stopped
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn] Stopped
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn](INFO): Running loop #6
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn] Running Loop=6
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:00:02.262Z,1734566402.262 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:00:04.262Z,1734566404.262 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000003.00,A,3648.13611,N,12147.20761,W,0.019,284.49,191224,,,A*77
2024-12-19T00:00:04.264Z,1734566404.264 [NAL9602](INFO): GPS fix at 20241219T000003: (36.802268, -121.786794)
2024-12-19T00:00:04.326Z,1734566404.326 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:00:04.326Z,1734566404.326 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:00:14.021Z,1734566414.021 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241218T231748/Courier0019.lzma
2024-12-19T00:00:15.023Z,1734566415.023 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0019.lzma.bak
2024-12-19T00:00:15.023Z,1734566415.023 [DataOverHttps](INFO): SBD MOMSN=23824772
2024-12-19T00:00:35.454Z,1734566435.454 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T231748/Express0020.lzma
2024-12-19T00:00:37.426Z,1734566437.426 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0020.lzma.bak
2024-12-19T00:00:37.426Z,1734566437.426 [DataOverHttps](INFO): SBD MOMSN=23824775
2024-12-19T00:00:39.457Z,1734566439.457 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:00:39.457Z,1734566439.457 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:00:39.457Z,1734566439.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:00:53.977Z,1734566453.977 [NAL9602](INFO): SBD MO Status=2, MOMSN=11430, MT Status=2, MTMSN=0
2024-12-19T00:00:53.977Z,1734566453.977 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-19T00:01:01.244Z,1734566461.244 [NAL9602](INFO): SBD MO Status=0, MOMSN=11430, MT Status=0, MTMSN=0
2024-12-19T00:01:01.244Z,1734566461.244 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:01:31.970Z,1734566491.970 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:03:28.739Z,1734566608.739 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T000327
2024-12-19T00:03:36.848Z,1734566616.848 [DataOverHttps](INFO): Received command: configset verticalcontrol.massdefault 1 cm persist
2024-12-19T00:03:37.610Z,1734566617.610 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 1 centimeter persist
2024-12-19T00:05:40.154Z,1734566740.154 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:05:40.154Z,1734566740.154 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:05:40.154Z,1734566740.154 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:05:40.154Z,1734566740.154 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:05:40.542Z,1734566740.542 [Default:CheckIn:D] Stopped
2024-12-19T00:05:40.542Z,1734566740.542 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:05:40.941Z,1734566740.941 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.651746 min
2024-12-19T00:05:40.941Z,1734566740.941 [Default:CheckIn:E] Stopped
2024-12-19T00:05:40.941Z,1734566740.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:05:40.941Z,1734566740.941 [Default:CheckIn] Stopped
2024-12-19T00:05:40.942Z,1734566740.942 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:05:40.942Z,1734566740.942 [Default:CheckIn](INFO): Running loop #7
2024-12-19T00:05:40.942Z,1734566740.942 [Default:CheckIn] Running Loop=7
2024-12-19T00:05:40.942Z,1734566740.942 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:05:40.942Z,1734566740.942 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:05:42.962Z,1734566742.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,000542.00,A,3648.13690,N,12147.20699,W,0.156,343.50,191224,,,D*75
2024-12-19T00:05:42.964Z,1734566742.964 [NAL9602](INFO): GPS fix at 20241219T000542: (36.802282, -121.786783)
2024-12-19T00:05:42.975Z,1734566742.975 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:05:42.975Z,1734566742.975 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:05:55.341Z,1734566755.341 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0022.lzma
2024-12-19T00:05:56.444Z,1734566756.444 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0022.lzma.bak
2024-12-19T00:05:56.444Z,1734566756.444 [DataOverHttps](INFO): SBD MOMSN=23824787
2024-12-19T00:06:15.445Z,1734566775.445 [DataOverHttps](INFO): Sending 252 bytes from file Logs/20241218T231748/Express0023.lzma
2024-12-19T00:06:16.448Z,1734566776.448 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0023.lzma.bak
2024-12-19T00:06:16.448Z,1734566776.448 [DataOverHttps](INFO): SBD MOMSN=23824790
2024-12-19T00:06:31.044Z,1734566791.044 [NAL9602](INFO): SBD MO Status=2, MOMSN=11431, MT Status=2, MTMSN=0
2024-12-19T00:06:31.044Z,1734566791.044 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-19T00:06:34.612Z,1734566794.612 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241218T231748/Express0026.lzma
2024-12-19T00:06:35.431Z,1734566795.431 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0026.lzma.bak
2024-12-19T00:06:35.431Z,1734566795.431 [DataOverHttps](INFO): SBD MOMSN=23824795
2024-12-19T00:06:40.874Z,1734566800.874 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:06:40.874Z,1734566800.874 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:06:40.874Z,1734566800.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:08:28.923Z,1734566908.923 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T000827
2024-12-19T00:08:37.253Z,1734566917.253 [DataOverHttps](INFO): Received command: configset verticalcontrol.massdefault 0.8 cm persist
2024-12-19T00:08:37.747Z,1734566917.747 [CommandExec](IMPORTANT): got command configSet VerticalControl.massDefault 0.8 centimeter persist
2024-12-19T00:10:38.904Z,1734567038.904 [NAL9602](INFO): SBD MO Status=2, MOMSN=11431, MT Status=2, MTMSN=0
2024-12-19T00:10:38.904Z,1734567038.904 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-19T00:10:45.374Z,1734567045.374 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-12-19T00:11:16.131Z,1734567076.131 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:11:41.215Z,1734567101.215 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:11:41.215Z,1734567101.215 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:11:41.215Z,1734567101.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:11:41.215Z,1734567101.215 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:11:41.614Z,1734567101.614 [Default:CheckIn:D] Stopped
2024-12-19T00:11:41.614Z,1734567101.614 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:11:42.027Z,1734567102.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 49.669613 min
2024-12-19T00:11:42.027Z,1734567102.027 [Default:CheckIn:E] Stopped
2024-12-19T00:11:42.027Z,1734567102.027 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:11:42.027Z,1734567102.027 [Default:CheckIn] Stopped
2024-12-19T00:11:42.028Z,1734567102.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:11:42.028Z,1734567102.028 [Default:CheckIn](INFO): Running loop #8
2024-12-19T00:11:42.028Z,1734567102.028 [Default:CheckIn] Running Loop=8
2024-12-19T00:11:42.028Z,1734567102.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:11:42.028Z,1734567102.028 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:11:44.024Z,1734567104.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001143.00,A,3648.13606,N,12147.20610,W,0.058,343.50,191224,,,D*70
2024-12-19T00:11:44.027Z,1734567104.027 [NAL9602](INFO): GPS fix at 20241219T001143: (36.802268, -121.786768)
2024-12-19T00:11:44.037Z,1734567104.037 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:11:44.037Z,1734567104.037 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:11:53.661Z,1734567113.661 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0028.lzma
2024-12-19T00:11:54.663Z,1734567114.663 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0028.lzma.bak
2024-12-19T00:11:54.663Z,1734567114.663 [DataOverHttps](INFO): SBD MOMSN=23824805
2024-12-19T00:12:10.741Z,1734567130.741 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241218T231748/Courier0031.lzma
2024-12-19T00:12:11.744Z,1734567131.744 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0031.lzma.bak
2024-12-19T00:12:11.744Z,1734567131.744 [DataOverHttps](INFO): SBD MOMSN=23824808
2024-12-19T00:12:14.727Z,1734567134.727 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-12-19T00:12:14.809Z,1734567134.809 [NAL9602](ERROR): received:
+CSQ:0
OK431, 2, 0, 0, 0
OK
2024-12-19T00:12:29.798Z,1734567149.798 [DataOverHttps](INFO): Sending 298 bytes from file Logs/20241218T231748/Express0029.lzma
2024-12-19T00:12:30.799Z,1734567150.799 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0029.lzma.bak
2024-12-19T00:12:30.800Z,1734567150.800 [DataOverHttps](INFO): SBD MOMSN=23824810
2024-12-19T00:12:49.156Z,1734567169.156 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241218T231748/Express0032.lzma
2024-12-19T00:12:50.155Z,1734567170.155 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0032.lzma.bak
2024-12-19T00:12:50.156Z,1734567170.156 [DataOverHttps](INFO): SBD MOMSN=23824816
2024-12-19T00:12:53.157Z,1734567173.157 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:12:53.157Z,1734567173.157 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:12:53.161Z,1734567173.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:14:02.646Z,1734567242.646 [NAL9602](INFO): SBD MO Status=2, MOMSN=11431, MT Status=2, MTMSN=0
2024-12-19T00:14:02.646Z,1734567242.646 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-19T00:15:24.297Z,1734567324.297 [NAL9602](INFO): SBD MO Status=0, MOMSN=11431, MT Status=0, MTMSN=0
2024-12-19T00:15:24.297Z,1734567324.297 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:15:54.996Z,1734567354.996 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:17:53.807Z,1734567473.807 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:17:53.807Z,1734567473.807 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:17:53.807Z,1734567473.807 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:17:53.807Z,1734567473.807 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:17:54.211Z,1734567474.211 [Default:CheckIn:D] Stopped
2024-12-19T00:17:54.211Z,1734567474.211 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:17:54.630Z,1734567474.630 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.879561 min
2024-12-19T00:17:54.630Z,1734567474.630 [Default:CheckIn:E] Stopped
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn] Stopped
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn](INFO): Running loop #9
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn] Running Loop=9
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:17:54.631Z,1734567474.631 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:17:56.618Z,1734567476.618 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,001755.00,A,3648.15521,N,12147.19248,W,0.583,28.09,191224,,,D*43
2024-12-19T00:17:56.620Z,1734567476.620 [NAL9602](INFO): GPS fix at 20241219T001755: (36.802587, -121.786541)
2024-12-19T00:17:56.633Z,1734567476.633 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:17:56.633Z,1734567476.633 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:18:04.741Z,1734567484.741 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0034.lzma
2024-12-19T00:18:05.744Z,1734567485.744 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0034.lzma.bak
2024-12-19T00:18:05.744Z,1734567485.744 [DataOverHttps](INFO): SBD MOMSN=23824826
2024-12-19T00:18:20.865Z,1734567500.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=11432, MT Status=2, MTMSN=0
2024-12-19T00:18:20.865Z,1734567500.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-12-19T00:18:23.605Z,1734567503.605 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T231748/Express0035.lzma
2024-12-19T00:18:24.608Z,1734567504.608 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0035.lzma.bak
2024-12-19T00:18:24.608Z,1734567504.608 [DataOverHttps](INFO): SBD MOMSN=23824829
2024-12-19T00:18:27.746Z,1734567507.746 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:18:27.746Z,1734567507.746 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:18:27.747Z,1734567507.747 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:19:04.896Z,1734567544.896 [NAL9602](INFO): SBD MO Status=0, MOMSN=11432, MT Status=0, MTMSN=0
2024-12-19T00:19:04.896Z,1734567544.896 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:19:35.607Z,1734567575.607 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:23:28.327Z,1734567808.327 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:23:28.327Z,1734567808.327 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:23:28.327Z,1734567808.327 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:23:28.327Z,1734567808.327 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:23:28.726Z,1734567808.726 [Default:CheckIn:D] Stopped
2024-12-19T00:23:28.726Z,1734567808.726 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:23:29.129Z,1734567809.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.454806 min
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn:E] Stopped
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn] Stopped
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn](INFO): Running loop #10
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn] Running Loop=10
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:23:29.131Z,1734567809.131 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:23:31.142Z,1734567811.142 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002330.00,A,3648.28125,N,12147.13962,W,2.605,12.71,191224,,,D*49
2024-12-19T00:23:31.144Z,1734567811.144 [NAL9602](INFO): GPS fix at 20241219T002330: (36.804688, -121.785660)
2024-12-19T00:23:31.156Z,1734567811.156 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:23:31.156Z,1734567811.156 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:23:40.382Z,1734567820.382 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0037.lzma
2024-12-19T00:23:41.442Z,1734567821.442 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0037.lzma.bak
2024-12-19T00:23:41.443Z,1734567821.443 [DataOverHttps](INFO): SBD MOMSN=23824841
2024-12-19T00:23:52.560Z,1734567832.560 [NAL9602](INFO): SBD MO Status=0, MOMSN=11433, MT Status=0, MTMSN=0
2024-12-19T00:23:52.560Z,1734567832.560 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:23:59.081Z,1734567839.081 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T231748/Express0038.lzma
2024-12-19T00:24:00.084Z,1734567840.084 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0038.lzma.bak
2024-12-19T00:24:00.084Z,1734567840.084 [DataOverHttps](INFO): SBD MOMSN=23824844
2024-12-19T00:24:03.078Z,1734567843.078 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:24:03.078Z,1734567843.078 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:24:03.078Z,1734567843.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:24:23.266Z,1734567863.266 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:29:03.676Z,1734568143.676 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-12-19T00:29:03.676Z,1734568143.676 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:29:03.676Z,1734568143.676 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:29:03.676Z,1734568143.676 [Default:CheckIn:D] Running Loop=1
2024-12-19T00:29:04.067Z,1734568144.067 [Default:CheckIn:D] Stopped
2024-12-19T00:29:04.067Z,1734568144.067 [Default:CheckIn:E] Running Loop=1
2024-12-19T00:29:04.476Z,1734568144.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.043827 min
2024-12-19T00:29:04.476Z,1734568144.476 [Default:CheckIn:E] Stopped
2024-12-19T00:29:04.476Z,1734568144.476 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-12-19T00:29:04.476Z,1734568144.476 [Default:CheckIn] Stopped
2024-12-19T00:29:04.477Z,1734568144.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:29:04.477Z,1734568144.477 [Default:CheckIn](INFO): Running loop #11
2024-12-19T00:29:04.477Z,1734568144.477 [Default:CheckIn] Running Loop=11
2024-12-19T00:29:04.477Z,1734568144.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:29:04.477Z,1734568144.477 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:29:06.477Z,1734568146.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,002905.00,A,3648.47816,N,12147.20301,W,3.071,260.84,191224,,,D*73
2024-12-19T00:29:06.479Z,1734568146.479 [NAL9602](INFO): GPS fix at 20241219T002905: (36.807969, -121.786717)
2024-12-19T00:29:06.499Z,1734568146.499 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:29:06.499Z,1734568146.499 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:29:18.509Z,1734568158.509 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0040.lzma
2024-12-19T00:29:19.511Z,1734568159.511 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0040.lzma.bak
2024-12-19T00:29:19.512Z,1734568159.512 [DataOverHttps](INFO): SBD MOMSN=23824853
2024-12-19T00:29:22.249Z,1734568162.249 [NAL9602](INFO): SBD MO Status=0, MOMSN=11434, MT Status=0, MTMSN=0
2024-12-19T00:29:22.249Z,1734568162.249 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:29:38.078Z,1734568178.078 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20241218T231748/Express0041.lzma
2024-12-19T00:29:39.079Z,1734568179.079 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0041.lzma.bak
2024-12-19T00:29:39.079Z,1734568179.079 [DataOverHttps](INFO): SBD MOMSN=23824857
2024-12-19T00:29:42.079Z,1734568182.079 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:29:42.079Z,1734568182.079 [Default:CheckIn:C.Wait] Running Loop=1
2024-12-19T00:29:42.079Z,1734568182.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:29:52.942Z,1734568192.942 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:30:29.484Z,1734568229.484 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003028
2024-12-19T00:30:37.046Z,1734568237.046 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m" 3ws2b 1 2
2024-12-19T00:30:37.126Z,1734568237.126 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m" 3ws2b 1 2.000000
2024-12-19T00:30:37.127Z,1734568237.127 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3ws2b
2024-12-19T00:30:37.128Z,1734568237.128 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='3ws2b'): "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m", ASAP
2024-12-19T00:30:38.047Z,1734568238.047 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003037
2024-12-19T00:30:45.701Z,1734568245.701 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" 3ws2b 2 2
2024-12-19T00:30:45.769Z,1734568245.769 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" 3ws2b 2 2.000000
2024-12-19T00:30:45.770Z,1734568245.770 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3ws2b
2024-12-19T00:30:45.771Z,1734568245.771 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='3ws2b'): "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2024-12-19T00:30:45.906Z,1734568245.906 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-12-19T00:30:45.906Z,1734568245.906 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-12-19T00:30:45.906Z,1734568245.906 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-12-19T00:30:45.909Z,1734568245.909 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-12-19T00:30:46.593Z,1734568246.593 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-12-19T00:30:46.599Z,1734568246.599 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-12-19T00:30:46.602Z,1734568246.602 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-12-19T00:30:46.604Z,1734568246.604 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-12-19T00:30:46.607Z,1734568246.607 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-12-19T00:30:46.610Z,1734568246.610 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-12-19T00:30:46.613Z,1734568246.613 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-12-19T00:30:46.615Z,1734568246.615 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-12-19T00:30:46.674Z,1734568246.674 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-12-19T00:30:46.677Z,1734568246.677 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-12-19T00:30:46.699Z,1734568246.699 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-12-19T00:30:46.703Z,1734568246.703 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003045
2024-12-19T00:30:46.710Z,1734568246.710 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-12-19T00:30:46.713Z,1734568246.713 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-12-19T00:30:46.724Z,1734568246.724 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-12-19T00:30:46.724Z,1734568246.724 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-12-19T00:30:46.724Z,1734568246.724 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-12-19T00:30:46.734Z,1734568246.734 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-12-19T00:30:47.174Z,1734568247.174 [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-12-19T00:30:47.179Z,1734568247.179 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-12-19T00:30:47.182Z,1734568247.182 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-12-19T00:30:47.185Z,1734568247.185 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-12-19T00:30:47.224Z,1734568247.224 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-12-19T00:30:47.228Z,1734568247.228 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-12-19T00:30:47.251Z,1734568247.251 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-12-19T00:30:47.273Z,1734568247.273 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-12-19T00:30:47.277Z,1734568247.277 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-12-19T00:30:47.323Z,1734568247.323 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-19T00:30:47.385Z,1734568247.385 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:30:47.385Z,1734568247.385 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:30:47.386Z,1734568247.386 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:30:47.559Z,1734568247.559 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-12-19T00:30:47.569Z,1734568247.569 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-12-19T00:30:47.580Z,1734568247.580 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-12-19T00:30:47.583Z,1734568247.583 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-12-19T00:30:47.594Z,1734568247.594 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-12-19T00:30:47.595Z,1734568247.595 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-12-19T00:30:47.611Z,1734568247.611 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-12-19T00:30:47.637Z,1734568247.637 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-12-19T00:30:47.694Z,1734568247.694 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-12-19T00:30:47.694Z,1734568247.694 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-12-19T00:30:47.695Z,1734568247.695 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-12-19T00:30:47.729Z,1734568247.729 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-12-19T00:30:47.737Z,1734568247.737 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-12-19T00:30:47.744Z,1734568247.744 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-12-19T00:30:47.747Z,1734568247.747 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-12-19T00:30:47.747Z,1734568247.747 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-12-19T00:30:47.749Z,1734568247.749 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-12-19T00:30:48.001Z,1734568248.001 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-12-19T00:30:48.017Z,1734568248.017 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-12-19T00:30:48.026Z,1734568248.026 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-12-19T00:30:48.031Z,1734568248.031 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-12-19T00:30:48.034Z,1734568248.034 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-12-19T00:30:48.037Z,1734568248.037 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-12-19T00:30:48.056Z,1734568248.056 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-12-19T00:30:48.062Z,1734568248.062 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-12-19T00:30:48.070Z,1734568248.070 [transit:MassHold.Pitch](DEBUG): Construct.
2024-12-19T00:30:48.077Z,1734568248.077 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-12-19T00:30:48.100Z,1734568248.100 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-12-19T00:30:48.119Z,1734568248.119 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-12-19T00:30:48.264Z,1734568248.264 [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-12-19T00:30:48.265Z,1734568248.265 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-12-19T00:30:58.205Z,1734568258.205 [DataOverHttps](INFO): Received command: strobe on
2024-12-19T00:31:00.139Z,1734568260.139 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 15 minute
2024-12-19T00:31:00.140Z,1734568260.140 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-12-19T00:31:00.141Z,1734568260.141 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-12-19T00:31:00.142Z,1734568260.142 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter
2024-12-19T00:31:00.142Z,1734568260.142 [CommandExec](IMPORTANT): got command set transit.Speed 1.3 meter_per_second
2024-12-19T00:31:00.143Z,1734568260.143 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1.5 meter
2024-12-19T00:31:00.145Z,1734568260.145 [CommandExec](IMPORTANT): got command strobe on
2024-12-19T00:31:00.145Z,1734568260.145 [CommandExec](IMPORTANT): Activating strobe
2024-12-19T00:31:09.658Z,1734568269.658 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.00001 kilometer
2024-12-19T00:31:09.659Z,1734568269.659 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter
2024-12-19T00:31:09.660Z,1734568269.660 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2024-12-19T00:31:09.660Z,1734568269.660 [CommandExec](IMPORTANT): got command run
2024-12-19T00:31:09.662Z,1734568269.662 [CommandExec](IMPORTANT): Running
2024-12-19T00:31:09.822Z,1734568269.822 [Default] Stopped
2024-12-19T00:31:09.822Z,1734568269.822 [Default](DEBUG): Aggregate::uninitialize Default
2024-12-19T00:31:09.822Z,1734568269.822 [Default:B.GoToSurface] Stopped
2024-12-19T00:31:09.822Z,1734568269.822 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-12-19T00:31:09.822Z,1734568269.822 [Default:CheckIn] Stopped
2024-12-19T00:31:09.822Z,1734568269.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:31:09.822Z,1734568269.822 [Default:CheckIn:C.Wait] Stopped
2024-12-19T00:31:09.823Z,1734568269.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:31:09.823Z,1734568269.823 [MissionManager](IMPORTANT): Started mission transit
2024-12-19T00:31:09.823Z,1734568269.823 [transit] Running Loop=1
2024-12-19T00:31:09.823Z,1734568269.823 [transit](DEBUG): Aggregate::initialize transit
2024-12-19T00:31:09.823Z,1734568269.823 [transit:StandardEnvelopes] Running Loop=1
2024-12-19T00:31:09.823Z,1734568269.823 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-12-19T00:31:09.823Z,1734568269.823 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-12-19T00:31:09.823Z,1734568269.823 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-12-19T00:31:09.824Z,1734568269.824 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-12-19T00:31:09.824Z,1734568269.824 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-12-19T00:31:09.824Z,1734568269.824 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-12-19T00:31:09.824Z,1734568269.824 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-12-19T00:31:09.824Z,1734568269.824 [transit:BackseatDriver] Running Loop=1
2024-12-19T00:31:09.824Z,1734568269.824 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-12-19T00:31:09.824Z,1734568269.824 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-12-19T00:31:09.824Z,1734568269.824 [transit:PowerOnly] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:A] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:B] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:C] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:D] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:E.Wait] Running Loop=1
2024-12-19T00:31:09.825Z,1734568269.825 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:31:09.826Z,1734568269.826 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-12-19T00:31:09.826Z,1734568269.826 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-12-19T00:31:09.826Z,1734568269.826 [transit:MassHold.Pitch] Running Loop=1
2024-12-19T00:31:09.826Z,1734568269.826 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-12-19T00:31:09.826Z,1734568269.826 [transit:B] Running Loop=1
2024-12-19T00:31:09.827Z,1734568269.827 [transit:MassHold.Pitch] Running Loop=1
2024-12-19T00:31:09.827Z,1734568269.827 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-12-19T00:31:09.828Z,1734568269.828 [transit:PowerOnly] Running Loop=1
2024-12-19T00:31:09.828Z,1734568269.828 [transit:PowerOnly:D] Stopped
2024-12-19T00:31:09.828Z,1734568269.828 [transit:PowerOnly:C] Stopped
2024-12-19T00:31:09.828Z,1734568269.828 [transit:PowerOnly:B] Stopped
2024-12-19T00:31:09.828Z,1734568269.828 [transit:PowerOnly:A] Stopped
2024-12-19T00:31:09.828Z,1734568269.828 [transit:BackseatDriver] Running Loop=1
2024-12-19T00:31:09.829Z,1734568269.829 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2024-12-19T00:31:09.838Z,1734568269.838 [BackseatComponent](INFO): Powering up
2024-12-19T00:31:09.838Z,1734568269.838 [BackseatComponent](INFO): Subscribing to LCM channels.
2024-12-19T00:31:10.004Z,1734568270.004 [transit:StandardEnvelopes] Running Loop=1
2024-12-19T00:31:10.004Z,1734568270.004 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-12-19T00:31:10.005Z,1734568270.005 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2024-12-19T00:31:10.033Z,1734568270.033 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-12-19T00:31:10.054Z,1734568270.054 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-12-19T00:31:10.055Z,1734568270.055 [transit:B] Stopped
2024-12-19T00:31:10.055Z,1734568270.055 [transit:C] Running Loop=1
2024-12-19T00:31:10.075Z,1734568270.075 [HorizontalControl](DEBUG): kpHeading = 0.400000
2024-12-19T00:31:10.076Z,1734568270.076 [HorizontalControl](DEBUG): kiHeading = 0.001000
2024-12-19T00:31:10.076Z,1734568270.076 [HorizontalControl](DEBUG): kdHeading = 0.050000
2024-12-19T00:31:10.291Z,1734568270.291 [transit:C] Stopped
2024-12-19T00:31:10.291Z,1734568270.291 [transit:E] Running Loop=1
2024-12-19T00:31:10.690Z,1734568270.690 [transit:E] Stopped
2024-12-19T00:31:10.690Z,1734568270.690 [transit:F] Running Loop=1
2024-12-19T00:31:11.054Z,1734568271.054 [transit:F] Stopped
2024-12-19T00:31:11.054Z,1734568271.054 [transit:G] Running Loop=1
2024-12-19T00:31:11.422Z,1734568271.422 [transit:G] Stopped
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit] Running Loop=1
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit:A.Pitch] Running Loop=1
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit:A.Pitch](DEBUG): Initialize.
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit:B.SetSpeed] Running Loop=1
2024-12-19T00:31:11.422Z,1734568271.422 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2024-12-19T00:31:11.423Z,1734568271.423 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2024-12-19T00:31:11.423Z,1734568271.423 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2024-12-19T00:31:11.426Z,1734568271.426 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2024-12-19T00:31:11.427Z,1734568271.427 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2024-12-19T00:31:11.830Z,1734568271.830 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2024-12-19T00:31:11.831Z,1734568271.831 [transit:Transit:B.SetSpeed] Running Loop=1
2024-12-19T00:31:11.831Z,1734568271.831 [transit:Transit:A.Pitch] Running Loop=1
2024-12-19T00:31:57.016Z,1734568317.016 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003156
2024-12-19T00:32:04.921Z,1734568324.921 [DataOverHttps](INFO): Received command: stop
2024-12-19T00:32:04.986Z,1734568324.986 [CommandExec](IMPORTANT): got command stop
2024-12-19T00:32:04.987Z,1734568324.987 [CommandExec](IMPORTANT): Scheduling is paused
2024-12-19T00:32:04.987Z,1734568324.987 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2024-12-19T00:32:04.998Z,1734568324.998 [MissionManager](INFO): MissionManager is completed.
2024-12-19T00:32:04.998Z,1734568324.998 [MissionManager](INFO): Uninitializing Mission transit
2024-12-19T00:32:04.998Z,1734568324.998 [transit] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit](DEBUG): Aggregate::uninitialize transit
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2024-12-19T00:32:04.999Z,1734568324.999 [transit:BackseatDriver] Stopped
2024-12-19T00:32:04.999Z,1734568324.999 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2024-12-19T00:32:04.999Z,1734568324.999 [transit:BackseatDriver:A.BackseatDriver] Stopped
2024-12-19T00:32:04.000Z,1734568325.000 [transit:PowerOnly] Stopped
2024-12-19T00:32:04.000Z,1734568325.000 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2024-12-19T00:32:04.000Z,1734568325.000 [transit:PowerOnly:E.Wait] Stopped
2024-12-19T00:32:04.000Z,1734568325.000 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:32:04.000Z,1734568325.000 [transit:BuoyancyHold.Buoyancy] Stopped
2024-12-19T00:32:05.000Z,1734568325.000 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2024-12-19T00:32:05.000Z,1734568325.000 [transit:MassHold.Pitch] Stopped
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit] Stopped
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit:A.Pitch] Stopped
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit:B.SetSpeed] Stopped
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2024-12-19T00:32:05.000Z,1734568325.000 [transit:Transit:Wpt1.Waypoint] Stopped
2024-12-19T00:32:05.001Z,1734568325.001 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2024-12-19T00:32:05.461Z,1734568325.461 [MissionManager](IMPORTANT): Started mission Default
2024-12-19T00:32:05.461Z,1734568325.461 [Default] Running Loop=1
2024-12-19T00:32:05.461Z,1734568325.461 [Default](DEBUG): Aggregate::initialize Default
2024-12-19T00:32:05.461Z,1734568325.461 [Default:B.GoToSurface] Running Loop=1
2024-12-19T00:32:05.461Z,1734568325.461 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-12-19T00:32:05.462Z,1734568325.462 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-12-19T00:32:05.462Z,1734568325.462 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-12-19T00:32:05.462Z,1734568325.462 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-12-19T00:32:05.463Z,1734568325.463 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-12-19T00:32:05.463Z,1734568325.463 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-12-19T00:32:05.463Z,1734568325.463 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-12-19T00:32:05.464Z,1734568325.464 [Default:A.Wait] Running Loop=1
2024-12-19T00:32:05.464Z,1734568325.464 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:32:05.738Z,1734568325.738 [BackseatComponent](INFO): Sent LCM shutdown request.
2024-12-19T00:32:05.739Z,1734568325.739 [BackseatComponent](INFO): Shutdown requested. Waiting (18.00 sec to power down.).
2024-12-19T00:32:18.731Z,1734568338.731 [Default:A.Wait](INFO): Done Waiting.
2024-12-19T00:32:18.731Z,1734568338.731 [Default:A.Wait] Stopped
2024-12-19T00:32:18.731Z,1734568338.731 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-12-19T00:32:19.192Z,1734568339.192 [Default:CheckIn] Running Loop=1
2024-12-19T00:32:19.192Z,1734568339.192 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-12-19T00:32:19.192Z,1734568339.192 [Default:CheckIn:Read_GPS] Running Loop=1
2024-12-19T00:32:21.136Z,1734568341.136 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,003220.00,A,3648.39287,N,12147.41079,W,3.868,222.76,191224,,,D*75
2024-12-19T00:32:21.138Z,1734568341.138 [NAL9602](INFO): GPS fix at 20241219T003220: (36.806548, -121.790180)
2024-12-19T00:32:21.149Z,1734568341.149 [Default:CheckIn:Read_GPS] Stopped
2024-12-19T00:32:21.149Z,1734568341.149 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-12-19T00:32:23.938Z,1734568343.938 [BackseatComponent](INFO): Powering down and starting shutdown timer (18.00 sec).
2024-12-19T00:32:23.939Z,1734568343.939 [BackseatComponent](INFO): Unsubscribing from LCM channels.
2024-12-19T00:32:23.939Z,1734568343.939 [LcmUniversalReporter](INFO): Deactivating messaging.
2024-12-19T00:32:30.041Z,1734568350.041 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241218T231748/Courier0043.lzma
2024-12-19T00:32:31.044Z,1734568351.044 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0043.lzma.bak
2024-12-19T00:32:31.044Z,1734568351.044 [DataOverHttps](INFO): SBD MOMSN=23824873
2024-12-19T00:32:39.324Z,1734568359.324 [NAL9602](INFO): SBD MO Status=0, MOMSN=11435, MT Status=0, MTMSN=0
2024-12-19T00:32:39.324Z,1734568359.324 [NAL9602](INFO): No messages in MT queue
2024-12-19T00:32:49.463Z,1734568369.463 [DataOverHttps](INFO): Sending 790 bytes from file Logs/20241218T231748/Express0044.lzma
2024-12-19T00:32:50.463Z,1734568370.463 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0044.lzma.bak
2024-12-19T00:32:50.464Z,1734568370.464 [DataOverHttps](INFO): SBD MOMSN=23824876
2024-12-19T00:33:00.664Z,1734568380.664 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003259
2024-12-19T00:33:10.066Z,1734568390.066 [NAL9602](INFO): Not Powering down - fast GPS
2024-12-19T00:33:10.933Z,1734568390.933 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20241218T231748/Express0047.lzma
2024-12-19T00:33:10.936Z,1734568390.936 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m" 3ws6r 1 2
2024-12-19T00:33:11.039Z,1734568391.039 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m" 3ws6r 1 2.000000
2024-12-19T00:33:11.039Z,1734568391.039 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=3ws6r
2024-12-19T00:33:11.040Z,1734568391.040 [CommandExec](IMPORTANT): Scheduled #3 (#1 of 2 with id='3ws6r'): "load Transport/transit.tl;set transit.MissionTimeout 15 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 0.5 m;set transit.Speed 1.3 m/s;set transit.MaxDepth 1.5 m", ASAP
2024-12-19T00:33:12.333Z,1734568392.333 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Express0047.lzma.bak
2024-12-19T00:33:12.333Z,1734568392.333 [DataOverHttps](IMPORTANT): SBD MOMSN=23824910, MTMSN=20241219T003311
2024-12-19T00:33:21.690Z,1734568401.690 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" 3ws6r 2 2
2024-12-19T00:33:21.771Z,1734568401.771 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run" 3ws6r 2 2.000000
2024-12-19T00:33:21.771Z,1734568401.771 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=3ws6r
2024-12-19T00:33:21.772Z,1734568401.772 [CommandExec](IMPORTANT): Scheduled #4 (#2 of 2 with id='3ws6r'): "set transit.MinOffshore 0.00001 km;set transit.MinAltitude 1 m;set transit:BackseatDriver.EnableBackseat 1 bool;run", ASAP
2024-12-19T00:33:21.878Z,1734568401.878 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2024-12-19T00:33:21.879Z,1734568401.879 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2024-12-19T00:33:21.879Z,1734568401.879 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2024-12-19T00:33:21.880Z,1734568401.880 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2024-12-19T00:33:22.640Z,1734568402.640 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2024-12-19T00:33:22.661Z,1734568402.661 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2024-12-19T00:33:22.679Z,1734568402.679 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2024-12-19T00:33:22.712Z,1734568402.712 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2024-12-19T00:33:22.726Z,1734568402.726 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2024-12-19T00:33:22.728Z,1734568402.728 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2024-12-19T00:33:22.774Z,1734568402.774 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2024-12-19T00:33:22.776Z,1734568402.776 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2024-12-19T00:33:22.787Z,1734568402.787 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2024-12-19T00:33:22.789Z,1734568402.789 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2024-12-19T00:33:22.802Z,1734568402.802 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2024-12-19T00:33:22.824Z,1734568402.824 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2024-12-19T00:33:22.826Z,1734568402.826 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2024-12-19T00:33:22.828Z,1734568402.828 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2024-12-19T00:33:22.828Z,1734568402.828 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2024-12-19T00:33:22.828Z,1734568402.828 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2024-12-19T00:33:22.829Z,1734568402.829 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2024-12-19T00:33:23.024Z,1734568403.024 [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-12-19T00:33:23.029Z,1734568403.029 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2024-12-19T00:33:23.031Z,1734568403.031 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2024-12-19T00:33:23.033Z,1734568403.033 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2024-12-19T00:33:23.035Z,1734568403.035 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2024-12-19T00:33:23.037Z,1734568403.037 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2024-12-19T00:33:23.039Z,1734568403.039 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2024-12-19T00:33:23.042Z,1734568403.042 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2024-12-19T00:33:23.044Z,1734568403.044 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2024-12-19T00:33:23.049Z,1734568403.049 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-12-19T00:33:23.066Z,1734568403.066 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:33:23.066Z,1734568403.066 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:33:23.067Z,1734568403.067 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2024-12-19T00:33:23.127Z,1734568403.127 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2024-12-19T00:33:23.131Z,1734568403.131 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2024-12-19T00:33:23.133Z,1734568403.133 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2024-12-19T00:33:23.135Z,1734568403.135 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2024-12-19T00:33:23.137Z,1734568403.137 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2024-12-19T00:33:23.138Z,1734568403.138 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2024-12-19T00:33:23.143Z,1734568403.143 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2024-12-19T00:33:23.150Z,1734568403.150 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2024-12-19T00:33:23.159Z,1734568403.159 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2024-12-19T00:33:23.160Z,1734568403.160 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2024-12-19T00:33:23.161Z,1734568403.161 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2024-12-19T00:33:23.183Z,1734568403.183 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2024-12-19T00:33:23.185Z,1734568403.185 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2024-12-19T00:33:23.187Z,1734568403.187 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2024-12-19T00:33:23.190Z,1734568403.190 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2024-12-19T00:33:23.190Z,1734568403.190 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2024-12-19T00:33:23.191Z,1734568403.191 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2024-12-19T00:33:23.277Z,1734568403.277 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2024-12-19T00:33:23.280Z,1734568403.280 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2024-12-19T00:33:23.282Z,1734568403.282 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2024-12-19T00:33:23.284Z,1734568403.284 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2024-12-19T00:33:23.286Z,1734568403.286 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2024-12-19T00:33:23.288Z,1734568403.288 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2024-12-19T00:33:23.294Z,1734568403.294 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2024-12-19T00:33:23.296Z,1734568403.296 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2024-12-19T00:33:23.299Z,1734568403.299 [transit:MassHold.Pitch](DEBUG): Construct.
2024-12-19T00:33:23.304Z,1734568403.304 [transit:Transit:A.Pitch](DEBUG): Construct.
2024-12-19T00:33:23.308Z,1734568403.308 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2024-12-19T00:33:23.311Z,1734568403.311 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2024-12-19T00:33:23.325Z,1734568403.325 [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-12-19T00:33:23.326Z,1734568403.326 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2024-12-19T00:33:35.346Z,1734568415.346 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 15 minute
2024-12-19T00:33:35.347Z,1734568415.347 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2024-12-19T00:33:35.347Z,1734568415.347 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2024-12-19T00:33:35.348Z,1734568415.348 [CommandExec](IMPORTANT): got command set transit.Depth 0.5 meter
2024-12-19T00:33:35.349Z,1734568415.349 [CommandExec](IMPORTANT): got command set transit.Speed 1.3 meter_per_second
2024-12-19T00:33:35.354Z,1734568415.354 [CommandExec](IMPORTANT): got command set transit.MaxDepth 1.5 meter
2024-12-19T00:33:38.825Z,1734568418.825 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241218T231748/Courier0049.lzma
2024-12-19T00:33:39.829Z,1734568419.829 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0049.lzma.bak
2024-12-19T00:33:39.829Z,1734568419.829 [DataOverHttps](INFO): SBD MOMSN=23824915
2024-12-19T00:33:43.411Z,1734568423.411 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.00001 kilometer
2024-12-19T00:33:43.411Z,1734568423.411 [CommandExec](IMPORTANT): got command set transit.MinAltitude 1 meter
2024-12-19T00:33:43.412Z,1734568423.412 [CommandExec](IMPORTANT): got command set transit:BackseatDriver.EnableBackseat 1 bool
2024-12-19T00:33:43.412Z,1734568423.412 [CommandExec](IMPORTANT): got command run
2024-12-19T00:33:43.414Z,1734568423.414 [CommandExec](IMPORTANT): Running
2024-12-19T00:33:43.619Z,1734568423.619 [Default] Stopped
2024-12-19T00:33:43.619Z,1734568423.619 [Default](DEBUG): Aggregate::uninitialize Default
2024-12-19T00:33:43.619Z,1734568423.619 [Default:B.GoToSurface] Stopped
2024-12-19T00:33:43.619Z,1734568423.619 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-12-19T00:33:43.619Z,1734568423.619 [Default:CheckIn] Stopped
2024-12-19T00:33:43.619Z,1734568423.619 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-12-19T00:33:43.619Z,1734568423.619 [Default:CheckIn:Read_Iridium] Stopped
2024-12-19T00:33:43.619Z,1734568423.619 [MissionManager](IMPORTANT): Started mission transit
2024-12-19T00:33:43.620Z,1734568423.620 [transit] Running Loop=1
2024-12-19T00:33:43.620Z,1734568423.620 [transit](DEBUG): Aggregate::initialize transit
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes] Running Loop=1
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-12-19T00:33:43.620Z,1734568423.620 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2024-12-19T00:33:43.621Z,1734568423.621 [transit:BackseatDriver] Running Loop=1
2024-12-19T00:33:43.621Z,1734568423.621 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2024-12-19T00:33:43.621Z,1734568423.621 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2024-12-19T00:33:43.621Z,1734568423.621 [transit:PowerOnly] Running Loop=1
2024-12-19T00:33:43.621Z,1734568423.621 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2024-12-19T00:33:43.621Z,1734568423.621 [transit:PowerOnly:A] Running Loop=1
2024-12-19T00:33:43.621Z,1734568423.621 [transit:PowerOnly:B] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:PowerOnly:C] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:PowerOnly:D] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:PowerOnly:E.Wait] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2024-12-19T00:33:43.622Z,1734568423.622 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2024-12-19T00:33:43.622Z,1734568423.622 [transit:MassHold.Pitch] Running Loop=1
2024-12-19T00:33:43.622Z,1734568423.622 [transit:MassHold.Pitch](DEBUG): Initialize.
2024-12-19T00:33:43.623Z,1734568423.623 [transit:B] Running Loop=1
2024-12-19T00:33:43.623Z,1734568423.623 [transit:MassHold.Pitch] Running Loop=1
2024-12-19T00:33:43.623Z,1734568423.623 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2024-12-19T00:33:43.624Z,1734568423.624 [transit:PowerOnly] Running Loop=1
2024-12-19T00:33:43.624Z,1734568423.624 [transit:PowerOnly:D] Stopped
2024-12-19T00:33:43.624Z,1734568423.624 [transit:PowerOnly:C] Stopped
2024-12-19T00:33:43.624Z,1734568423.624 [transit:PowerOnly:B] Stopped
2024-12-19T00:33:43.624Z,1734568423.624 [transit:PowerOnly:A] Stopped
2024-12-19T00:33:43.624Z,1734568423.624 [transit:BackseatDriver] Running Loop=1
2024-12-19T00:33:43.624Z,1734568423.624 [transit:BackseatDriver:A.BackseatDriver](INFO): Initializing backseat
2024-12-19T00:33:43.690Z,1734568423.690 [transit:StandardEnvelopes] Running Loop=1
2024-12-19T00:33:43.690Z,1734568423.690 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2024-12-19T00:33:43.690Z,1734568423.690 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2024-12-19T00:33:43.706Z,1734568423.706 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2024-12-19T00:33:43.706Z,1734568423.706 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2024-12-19T00:33:43.707Z,1734568423.707 [transit:B] Stopped
2024-12-19T00:33:43.707Z,1734568423.707 [transit:C] Running Loop=1
2024-12-19T00:33:43.814Z,1734568423.814 [BackseatComponent](INFO): Powering up
2024-12-19T00:33:43.814Z,1734568423.814 [BackseatComponent](INFO): Subscribing to LCM channels.
2024-12-19T00:33:44.040Z,1734568424.040 [transit:C] Stopped
2024-12-19T00:33:44.040Z,1734568424.040 [transit:E] Running Loop=1
2024-12-19T00:33:44.438Z,1734568424.438 [transit:E] Stopped
2024-12-19T00:33:44.438Z,1734568424.438 [transit:F] Running Loop=1
2024-12-19T00:33:44.862Z,1734568424.862 [transit:F] Stopped
2024-12-19T00:33:44.862Z,1734568424.862 [transit:G] Running Loop=1
2024-12-19T00:33:45.250Z,1734568425.250 [transit:G] Stopped
2024-12-19T00:33:45.250Z,1734568425.250 [transit:Transit] Running Loop=1
2024-12-19T00:33:45.250Z,1734568425.250 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2024-12-19T00:33:45.250Z,1734568425.250 [transit:Transit:A.Pitch] Running Loop=1
2024-12-19T00:33:45.251Z,1734568425.251 [transit:Transit:A.Pitch](DEBUG): Initialize.
2024-12-19T00:33:45.251Z,1734568425.251 [transit:Transit:B.SetSpeed] Running Loop=1
2024-12-19T00:33:45.251Z,1734568425.251 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2024-12-19T00:33:45.251Z,1734568425.251 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2024-12-19T00:33:45.251Z,1734568425.251 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2024-12-19T00:33:45.252Z,1734568425.252 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2024-12-19T00:33:45.278Z,1734568425.278 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2024-12-19T00:33:45.657Z,1734568425.657 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2024-12-19T00:33:45.662Z,1734568425.662 [transit:Transit:B.SetSpeed] Running Loop=1
2024-12-19T00:33:45.662Z,1734568425.662 [transit:Transit:A.Pitch] Running Loop=1
2024-12-19T00:33:59.057Z,1734568439.057 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241218T231748/Courier0052.lzma
2024-12-19T00:34:00.060Z,1734568440.060 [DataOverHttps](INFO): Moved sent file to Logs/20241218T231748/Courier0052.lzma.bak
2024-12-19T00:34:00.060Z,1734568440.060 [DataOverHttps](INFO): SBD MOMSN=23824917
2024-12-19T00:34:53.220Z,1734568493.220 [BackseatComponent](IMPORTANT): IBASE811F-02: Running bioacoustics backseat app. Logging to: 20241219T003452
2024-12-19T00:34:55.929Z,1734568495.929 [BackseatComponent](INFO): Created universal data reader for depth with code: 6
2024-12-19T00:34:55.929Z,1734568495.929 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1
2024-12-19T00:34:55.932Z,1734568495.932 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23
2024-12-19T00:34:55.933Z,1734568495.933 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2
2024-12-19T00:34:55.936Z,1734568495.936 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26
2024-12-19T00:34:55.936Z,1734568495.936 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3
2024-12-19T00:34:55.973Z,1734568495.973 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52
2024-12-19T00:34:55.973Z,1734568495.973 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4
2024-12-19T00:34:55.980Z,1734568495.980 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54
2024-12-19T00:34:55.981Z,1734568495.981 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5
2024-12-19T00:34:55.996Z,1734568495.996 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59
2024-12-19T00:34:55.996Z,1734568495.996 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6
2024-12-19T00:34:56.023Z,1734568496.023 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70
2024-12-19T00:34:56.024Z,1734568496.024 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7
2024-12-19T00:35:31.367Z,1734568531.367 [DataOverHttps](IMPORTANT): SBD MTMSN=20241219T003530
2024-12-19T00:35:39.666Z,1734568539.666 [DataOverHttps](INFO): Received command: restart logs