2024-07-19T01:38:34.923Z,1721353114.923 [Supervisor](DEBUG): Initializing supervisor. 2024-07-19T01:38:34.927Z,1721353114.927 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-07-19T01:38:34.927Z,1721353114.927 [SyncHandler](INFO): Protected caller Thread ID is 1309 2024-07-19T01:38:34.928Z,1721353114.928 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-07-19T01:38:34.929Z,1721353114.929 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-07-19T01:38:34.929Z,1721353114.929 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1310 2024-07-19T01:38:34.933Z,1721353114.933 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-07-19T01:38:34.950Z,1721353114.950 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-07-19T01:38:34.951Z,1721353114.951 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-07-19T01:38:34.951Z,1721353114.951 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1311 2024-07-19T01:38:34.955Z,1721353114.955 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-07-19T01:38:34.956Z,1721353114.956 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-07-19T01:38:34.957Z,1721353114.957 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1312 2024-07-19T01:38:34.959Z,1721353114.959 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-07-19T01:38:34.960Z,1721353114.960 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-07-19T01:38:34.960Z,1721353114.960 [logger ThreadHandler](INFO): Protected caller Thread ID is 1313 2024-07-19T01:38:34.964Z,1721353114.964 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-07-19T01:38:34.964Z,1721353114.964 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-07-19T01:38:34.966Z,1721353114.966 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-07-19T01:38:35.455Z,1721353115.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-07-19T01:38:35.455Z,1721353115.455 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-07-19T01:38:35.685Z,1721353115.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-07-19T01:38:35.686Z,1721353115.686 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-07-19T01:38:35.778Z,1721353115.778 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-07-19T01:38:36.029Z,1721353116.029 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-07-19T01:38:36.029Z,1721353116.029 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-07-19T01:38:36.119Z,1721353116.119 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-07-19T01:38:36.226Z,1721353116.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-07-19T01:38:36.227Z,1721353116.227 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-07-19T01:38:36.589Z,1721353116.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-07-19T01:38:36.589Z,1721353116.589 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-07-19T01:38:36.735Z,1721353116.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-07-19T01:38:36.736Z,1721353116.736 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-07-19T01:38:37.293Z,1721353117.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-07-19T01:38:37.294Z,1721353117.294 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-07-19T01:38:37.497Z,1721353117.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-07-19T01:38:37.498Z,1721353117.498 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-07-19T01:38:37.928Z,1721353117.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-07-19T01:38:37.928Z,1721353117.928 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-07-19T01:38:38.236Z,1721353118.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-07-19T01:38:38.236Z,1721353118.236 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-07-19T01:38:38.358Z,1721353118.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-07-19T01:38:38.359Z,1721353118.359 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-07-19T01:38:39.235Z,1721353119.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-07-19T01:38:39.235Z,1721353119.235 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-07-19T01:38:39.958Z,1721353119.958 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-07-19T01:38:39.959Z,1721353119.959 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-07-19T01:38:40.204Z,1721353120.204 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-07-19T01:38:40.206Z,1721353120.206 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2024-07-19T01:38:40.207Z,1721353120.207 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2024-07-19T01:38:40.338Z,1721353120.338 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2024-07-19T01:38:40.432Z,1721353120.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2024-07-19T01:38:40.582Z,1721353120.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2024-07-19T01:38:40.679Z,1721353120.679 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2024-07-19T01:38:40.954Z,1721353120.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-07-19T01:38:40.954Z,1721353120.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2024-07-19T01:38:41.071Z,1721353121.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2024-07-19T01:38:41.190Z,1721353121.190 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2024-07-19T01:38:41.311Z,1721353121.311 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2024-07-19T01:38:41.473Z,1721353121.473 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2024-07-19T01:38:41.582Z,1721353121.582 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2024-07-19T01:38:41.680Z,1721353121.680 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-07-19T01:38:41.686Z,1721353121.686 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-07-19T01:38:41.697Z,1721353121.697 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-07-19T01:38:41.697Z,1721353121.697 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-07-19T01:38:41.807Z,1721353121.807 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-07-19T01:38:41.807Z,1721353121.807 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-07-19T01:38:41.859Z,1721353121.859 [VerticalControl](DEBUG): Construct VerticalControl. 2024-07-19T01:38:41.919Z,1721353121.919 [VerticalControl] Loaded 2024-07-19T01:38:41.919Z,1721353121.919 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-07-19T01:38:41.922Z,1721353121.922 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-07-19T01:38:41.969Z,1721353121.969 [HorizontalControl] Loaded 2024-07-19T01:38:41.969Z,1721353121.969 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-07-19T01:38:41.971Z,1721353121.971 [SpeedControl](DEBUG): Construct SpeedControl. 2024-07-19T01:38:41.975Z,1721353121.975 [SpeedControl] Loaded 2024-07-19T01:38:41.975Z,1721353121.975 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-07-19T01:38:41.977Z,1721353121.977 [LoopControl](DEBUG): Construct LoopControl. 2024-07-19T01:38:41.978Z,1721353121.978 [LoopControl] Loaded 2024-07-19T01:38:41.978Z,1721353121.978 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-07-19T01:38:41.978Z,1721353121.978 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-07-19T01:38:41.979Z,1721353121.979 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-07-19T01:38:42.133Z,1721353122.133 [BuoyancyServo] Loaded 2024-07-19T01:38:42.134Z,1721353122.134 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-07-19T01:38:42.156Z,1721353122.156 [ElevatorServo] Loaded 2024-07-19T01:38:42.156Z,1721353122.156 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-07-19T01:38:42.176Z,1721353122.176 [MassServo] Loaded 2024-07-19T01:38:42.177Z,1721353122.177 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-07-19T01:38:42.197Z,1721353122.197 [RudderServo] Loaded 2024-07-19T01:38:42.197Z,1721353122.197 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-07-19T01:38:42.213Z,1721353122.213 [ThrusterHE] Loaded 2024-07-19T01:38:42.213Z,1721353122.213 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-07-19T01:38:42.214Z,1721353122.214 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-07-19T01:38:42.214Z,1721353122.214 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-07-19T01:38:42.263Z,1721353122.263 [DepthRateCalculator] Loaded 2024-07-19T01:38:42.264Z,1721353122.264 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-07-19T01:38:42.269Z,1721353122.269 [PitchRateCalculator] Loaded 2024-07-19T01:38:42.269Z,1721353122.269 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-07-19T01:38:42.278Z,1721353122.278 [SpeedCalculator] Loaded 2024-07-19T01:38:42.279Z,1721353122.279 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-07-19T01:38:42.283Z,1721353122.283 [YawRateCalculator] Loaded 2024-07-19T01:38:42.283Z,1721353122.283 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-07-19T01:38:42.301Z,1721353122.301 [ElevatorOffsetCalculator] Loaded 2024-07-19T01:38:42.301Z,1721353122.301 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-07-19T01:38:42.302Z,1721353122.302 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-07-19T01:38:42.302Z,1721353122.302 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-07-19T01:38:42.412Z,1721353122.412 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-07-19T01:38:42.412Z,1721353122.412 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-07-19T01:38:42.426Z,1721353122.426 [NavChart] Loaded 2024-07-19T01:38:42.426Z,1721353122.426 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-07-19T01:38:42.432Z,1721353122.432 [UniversalFixResidualReporter] Loaded 2024-07-19T01:38:42.432Z,1721353122.432 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-07-19T01:38:42.432Z,1721353122.432 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-07-19T01:38:42.433Z,1721353122.433 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-07-19T01:38:42.859Z,1721353122.859 [AHRS_M2] Loaded 2024-07-19T01:38:42.859Z,1721353122.859 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-07-19T01:38:42.898Z,1721353122.898 [BackseatComponent] Loaded 2024-07-19T01:38:42.898Z,1721353122.898 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-07-19T01:38:42.899Z,1721353122.899 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0 2024-07-19T01:38:42.900Z,1721353122.900 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1395 2024-07-19T01:38:42.903Z,1721353122.903 [LcmUniversalReporter] Loaded 2024-07-19T01:38:42.903Z,1721353122.903 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-07-19T01:38:42.909Z,1721353122.909 [BioacousticsDataBridge] Loaded 2024-07-19T01:38:42.909Z,1721353122.909 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2024-07-19T01:38:43.737Z,1721353123.737 [BPC1] Loaded 2024-07-19T01:38:43.737Z,1721353123.737 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-07-19T01:38:43.809Z,1721353123.809 [DataOverHttps] Loaded 2024-07-19T01:38:43.809Z,1721353123.809 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-07-19T01:38:43.810Z,1721353123.810 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0 2024-07-19T01:38:43.811Z,1721353123.811 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1396 2024-07-19T01:38:43.834Z,1721353123.834 [Depth_Keller] Loaded 2024-07-19T01:38:43.834Z,1721353123.834 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-07-19T01:38:43.839Z,1721353123.839 [DropWeight] Loaded 2024-07-19T01:38:43.839Z,1721353123.839 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-07-19T01:38:43.902Z,1721353123.902 [NAL9602] Loaded 2024-07-19T01:38:43.903Z,1721353123.903 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-07-19T01:38:43.932Z,1721353123.932 [Onboard] Loaded 2024-07-19T01:38:43.932Z,1721353123.932 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-07-19T01:38:43.933Z,1721353123.933 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0 2024-07-19T01:38:43.934Z,1721353123.934 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1397 2024-07-19T01:38:43.948Z,1721353123.948 [Power24vConverter] Loaded 2024-07-19T01:38:43.948Z,1721353123.948 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-07-19T01:38:43.963Z,1721353123.963 [Radio_Surface] Loaded 2024-07-19T01:38:43.964Z,1721353123.964 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-07-19T01:38:43.965Z,1721353123.965 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0 2024-07-19T01:38:43.965Z,1721353123.965 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1398 2024-07-19T01:38:43.980Z,1721353123.980 [Sonardyne_Nano] Loaded 2024-07-19T01:38:43.980Z,1721353123.980 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-07-19T01:38:43.981Z,1721353123.981 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-07-19T01:38:43.982Z,1721353123.982 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-07-19T01:38:44.035Z,1721353124.035 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-07-19T01:38:44.036Z,1721353124.036 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-07-19T01:38:44.203Z,1721353124.203 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-07-19T01:38:44.204Z,1721353124.204 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-07-19T01:38:44.230Z,1721353124.230 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-07-19T01:38:44.231Z,1721353124.231 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-07-19T01:38:44.588Z,1721353124.588 [CTD_Seabird] Loaded 2024-07-19T01:38:44.589Z,1721353124.589 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-07-19T01:38:44.590Z,1721353124.590 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0 2024-07-19T01:38:44.590Z,1721353124.590 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1399 2024-07-19T01:38:44.612Z,1721353124.612 [PAR_Licor] Loaded 2024-07-19T01:38:44.612Z,1721353124.612 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-07-19T01:38:44.657Z,1721353124.657 [WetLabsBB2FL] Loaded 2024-07-19T01:38:44.658Z,1721353124.658 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-07-19T01:38:44.659Z,1721353124.659 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0 2024-07-19T01:38:44.659Z,1721353124.659 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1400 2024-07-19T01:38:44.660Z,1721353124.660 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-07-19T01:38:44.660Z,1721353124.660 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-07-19T01:38:45.099Z,1721353125.099 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-07-19T01:38:45.099Z,1721353125.099 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-07-19T01:38:45.239Z,1721353125.239 [SBIT](DEBUG): Construct Startup Built In Test. 2024-07-19T01:38:45.248Z,1721353125.248 [SBIT] Loaded 2024-07-19T01:38:45.249Z,1721353125.249 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-07-19T01:38:45.252Z,1721353125.252 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-07-19T01:38:45.264Z,1721353125.264 [IBIT] Loaded 2024-07-19T01:38:45.264Z,1721353125.264 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-07-19T01:38:45.270Z,1721353125.270 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-07-19T01:38:45.374Z,1721353125.374 [CBIT] Loaded 2024-07-19T01:38:45.374Z,1721353125.374 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-07-19T01:38:45.375Z,1721353125.375 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-07-19T01:38:45.381Z,1721353125.381 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-07-19T01:38:45.384Z,1721353125.384 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-07-19T01:38:45.395Z,1721353125.395 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-07-19T01:38:45.396Z,1721353125.396 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0 2024-07-19T01:38:45.397Z,1721353125.397 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1401 2024-07-19T01:38:45.401Z,1721353125.401 [Supervisor](INFO): Main Thread ID is 830 2024-07-19T01:38:45.401Z,1721353125.401 [Supervisor](DEBUG): Running supervisor. 2024-07-19T01:38:45.402Z,1721353125.402 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1402 2024-07-19T01:38:45.403Z,1721353125.403 [CommandExec](INFO): Initializing the command executive. 2024-07-19T01:38:45.404Z,1721353125.404 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1403 2024-07-19T01:38:45.406Z,1721353125.406 [controlThread ThreadHandler](INFO): Handler Thread ID is 1404 2024-07-19T01:38:45.406Z,1721353125.406 [controlThread](DEBUG): Initializing ControlThread 2024-07-19T01:38:45.407Z,1721353125.407 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-07-19T01:38:45.409Z,1721353125.409 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-07-19T01:38:45.410Z,1721353125.410 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-07-19T01:38:45.410Z,1721353125.410 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-07-19T01:38:45.411Z,1721353125.411 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-07-19T01:38:45.412Z,1721353125.412 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-07-19T01:38:45.412Z,1721353125.412 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-07-19T01:38:45.412Z,1721353125.412 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-07-19T01:38:45.413Z,1721353125.413 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-07-19T01:38:45.414Z,1721353125.414 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-07-19T01:38:45.414Z,1721353125.414 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-07-19T01:38:45.422Z,1721353125.422 [SBIT](INFO): Initialize SBIT Component. 2024-07-19T01:38:45.423Z,1721353125.423 [SBIT](IMPORTANT): git: 2024-07-18 2024-07-19T01:38:45.423Z,1721353125.423 [SBIT](INFO): git hash: e777c895b2d7d743d45b75a88845e2c8d3f60819 2024-07-19T01:38:45.423Z,1721353125.423 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-07-19T01:38:45.424Z,1721353125.424 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-07-19T01:38:45.425Z,1721353125.425 [SBIT](INFO): Beginning SBIT in 110.000000 seconds. 2024-07-19T01:38:45.426Z,1721353125.426 [IBIT](INFO): Initialize IBIT Component. 2024-07-19T01:38:45.427Z,1721353125.427 [CBIT](DEBUG): Initialize CBIT Component. 2024-07-19T01:38:45.428Z,1721353125.428 [logger ThreadHandler](INFO): Handler Thread ID is 1405 2024-07-19T01:38:45.438Z,1721353125.438 [CBIT](DEBUG): Initialized mux pins. 2024-07-19T01:38:45.438Z,1721353125.438 [CBIT](DEBUG): Initializing the watchdog timer. 2024-07-19T01:38:45.446Z,1721353125.446 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1406 2024-07-19T01:38:45.458Z,1721353125.458 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1407 2024-07-19T01:38:45.460Z,1721353125.460 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-07-19T01:38:45.462Z,1721353125.462 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-07-19T01:38:45.462Z,1721353125.462 [CBIT](DEBUG): Initializing heartbeat. 2024-07-19T01:38:45.470Z,1721353125.470 [Onboard ThreadHandler](INFO): Handler Thread ID is 1408 2024-07-19T01:38:45.488Z,1721353125.488 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1409 2024-07-19T01:38:45.510Z,1721353125.510 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1410 2024-07-19T01:38:45.511Z,1721353125.511 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-07-19T01:38:45.515Z,1721353125.515 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1412 2024-07-19T01:38:45.518Z,1721353125.518 [WetLabsBB2FL](INFO): Powering up 2024-07-19T01:38:45.520Z,1721353125.520 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1414 2024-07-19T01:38:45.521Z,1721353125.521 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000 2024-07-19T01:38:45.523Z,1721353125.523 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000 2024-07-19T01:38:45.523Z,1721353125.523 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:38:45.524Z,1721353125.524 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000 2024-07-19T01:38:45.524Z,1721353125.524 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:38:45.526Z,1721353125.526 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000 2024-07-19T01:38:45.527Z,1721353125.527 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:38:45.528Z,1721353125.528 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000 2024-07-19T01:38:45.528Z,1721353125.528 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:38:45.530Z,1721353125.530 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000 2024-07-19T01:38:45.530Z,1721353125.530 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:38:45.532Z,1721353125.532 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000 2024-07-19T01:38:45.532Z,1721353125.532 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:38:45.533Z,1721353125.533 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MF.000 2024-07-19T01:38:45.533Z,1721353125.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:38:45.534Z,1721353125.534 [CBIT](DEBUG): Deactivating GF circuits. 2024-07-19T01:38:45.534Z,1721353125.534 [CBIT](DEBUG): Deactivating emergency mode. 2024-07-19T01:38:45.539Z,1721353125.539 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5MI61M.000 2024-07-19T01:38:45.546Z,1721353125.546 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:38:45.574Z,1721353125.574 [CBIT](DEBUG): Backplane powered. 2024-07-19T01:38:45.575Z,1721353125.575 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-07-19T01:38:45.576Z,1721353125.576 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-07-19T01:38:45.576Z,1721353125.576 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-07-19T01:38:45.577Z,1721353125.577 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-07-19T01:38:45.612Z,1721353125.612 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-07-19T01:38:45.616Z,1721353125.616 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-19T01:38:45.640Z,1721353125.640 [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-07-19T01:38:45.641Z,1721353125.641 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-07-19T01:38:45.641Z,1721353125.641 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-07-19T01:38:45.674Z,1721353125.674 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-07-19T01:38:45.894Z,1721353125.894 [Radio_Surface](INFO): Powering up 2024-07-19T01:38:45.916Z,1721353125.916 [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-07-19T01:38:45.938Z,1721353125.938 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-07-19T01:38:45.939Z,1721353125.939 [Default:A.Wait](DEBUG): Construct Wait. 2024-07-19T01:38:45.941Z,1721353125.941 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-07-19T01:38:46.002Z,1721353126.002 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-07-19T01:38:46.005Z,1721353126.005 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-07-19T01:38:46.019Z,1721353126.019 [Default:E.Execute](DEBUG): Construct Execute. 2024-07-19T01:38:46.022Z,1721353126.022 [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-07-19T01:38:46.028Z,1721353126.028 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-07-19T01:38:46.046Z,1721353126.046 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-07-19T01:38:46.120Z,1721353126.120 [Power24vConverter](INFO): Powering up. 2024-07-19T01:38:46.121Z,1721353126.121 [Sonardyne_Nano](INFO): Initializing. 2024-07-19T01:38:46.180Z,1721353126.180 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-07-19T01:38:46.186Z,1721353126.186 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-07-19T01:38:46.187Z,1721353126.187 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-07-19T01:38:46.194Z,1721353126.194 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-07-19T01:38:46.195Z,1721353126.195 [MassServo](DEBUG): Initializing EZServoServo. 2024-07-19T01:38:46.202Z,1721353126.202 [MassServo](DEBUG): Initializing MassServo. 2024-07-19T01:38:46.203Z,1721353126.203 [RudderServo](DEBUG): Initializing EZServoServo. 2024-07-19T01:38:46.210Z,1721353126.210 [RudderServo](DEBUG): Initializing RudderServo. 2024-07-19T01:38:46.211Z,1721353126.211 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-07-19T01:38:46.214Z,1721353126.214 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-07-19T01:38:47.626Z,1721353127.626 [WetLabsBB2FL](INFO): Powering down 2024-07-19T01:38:48.056Z,1721353128.056 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-07-19T01:38:49.344Z,1721353129.344 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107 2024-07-19T01:38:51.939Z,1721353131.939 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-07-19T01:38:52.542Z,1721353132.542 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-07-19T01:38:55.281Z,1721353135.281 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:39:10.784Z,1721353150.784 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:39:13.932Z,1721353153.932 [NAL9602](INFO): Powering up NAL9602 2024-07-19T01:39:21.571Z,1721353161.571 [NavChartDb](INFO): # of records loaded: 10000 2024-07-19T01:39:24.840Z,1721353164.840 [NAL9602](INFO): NAL9602 initialized 2024-07-19T01:39:32.198Z,1721353172.198 [NavChartDb](INFO): # of records loaded: 15000 2024-07-19T01:39:41.707Z,1721353181.707 [NavChartDb](INFO): # of records loaded: 20000 2024-07-19T01:39:49.489Z,1721353189.489 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-07-19T01:39:51.177Z,1721353191.177 [NavChartDb](INFO): # of records loaded: 25000 2024-07-19T01:39:58.647Z,1721353198.647 [NavChartDb](INFO): # of records loaded: 30000 2024-07-19T01:40:04.512Z,1721353204.512 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:40:05.673Z,1721353205.673 [NavChartDb](INFO): # of records loaded: 35000 2024-07-19T01:40:06.451Z,1721353206.451 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:40:14.166Z,1721353214.166 [NavChartDb](INFO): # of records loaded: 40000 2024-07-19T01:40:21.501Z,1721353221.501 [NavChartDb](INFO): # of records loaded: 45000 2024-07-19T01:40:21.803Z,1721353221.803 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:40:29.175Z,1721353229.175 [NavChartDb](INFO): # of records loaded: 50000 2024-07-19T01:40:35.982Z,1721353235.982 [SBIT](IMPORTANT): Beginning Startup BIT 2024-07-19T01:40:35.991Z,1721353235.991 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-19T01:40:36.727Z,1721353236.727 [NavChartDb](INFO): # of records loaded: 55000 2024-07-19T01:40:37.560Z,1721353237.560 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:40:44.651Z,1721353244.651 [NavChartDb](INFO): # of records loaded: 60000 2024-07-19T01:40:46.923Z,1721353246.923 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.004181 CHAN A1 (24V): -0.007687 CHAN A2 (12V): -0.003745 CHAN A3 (5V): -0.003002 CHAN B0 (3.3V): -0.000858 CHAN B1 (3.15aV): -0.001308 CHAN B2 (3.15bV): -0.001252 CHAN B3 (GND): -0.000494 OPEN: 0.003693 Full Scale: +/- 1 mA 2024-07-19T01:40:49.286Z,1721353249.286 [NAL9602](INFO): SBD MO Status=0, MOMSN=5275, MT Status=0, MTMSN=0 2024-07-19T01:40:49.286Z,1721353249.286 [NAL9602](INFO): No messages in MT queue 2024-07-19T01:40:52.424Z,1721353252.424 [NavChartDb](INFO): # of records loaded: 65000 2024-07-19T01:40:52.590Z,1721353252.590 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:40:53.719Z,1721353253.719 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:41:00.349Z,1721353260.349 [NavChartDb](INFO): # of records loaded: 70000 2024-07-19T01:41:07.989Z,1721353267.989 [NavChartDb](INFO): # of records loaded: 75000 2024-07-19T01:41:08.667Z,1721353268.667 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:41:15.893Z,1721353275.893 [NavChartDb](INFO): # of records loaded: 80000 2024-07-19T01:41:24.424Z,1721353284.424 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:41:25.035Z,1721353285.035 [NavChartDb](INFO): # of records loaded: 85000 2024-07-19T01:41:30.120Z,1721353290.120 [SBIT](IMPORTANT): SBIT PASSED 2024-07-19T01:41:30.121Z,1721353290.121 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-07-19T01:41:30.121Z,1721353290.121 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count; 2024-07-19T01:41:30.121Z,1721353290.121 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit; 2024-07-19T01:41:30.121Z,1721353290.121 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-07-19T01:41:30.122Z,1721353290.122 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter; 2024-07-19T01:41:30.146Z,1721353290.146 [SBIT](IMPORTANT): VerticalControl.massDefault=2.25 centimeter; 2024-07-19T01:41:30.519Z,1721353290.519 [MissionManager](IMPORTANT): Started mission Startup 2024-07-19T01:41:30.520Z,1721353290.520 [Startup] Running Loop=1 2024-07-19T01:41:30.520Z,1721353290.520 [Startup](DEBUG): Aggregate::initialize Startup 2024-07-19T01:41:30.520Z,1721353290.520 [Startup:A.GoToSurface] Running Loop=1 2024-07-19T01:41:30.520Z,1721353290.520 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-19T01:41:30.520Z,1721353290.520 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-19T01:41:30.521Z,1721353290.521 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-19T01:41:30.521Z,1721353290.521 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-19T01:41:30.522Z,1721353290.522 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-19T01:41:30.526Z,1721353290.526 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-19T01:41:30.527Z,1721353290.527 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-19T01:41:30.528Z,1721353290.528 [Startup:StartupSatComms] Running Loop=1 2024-07-19T01:41:30.528Z,1721353290.528 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-07-19T01:41:30.528Z,1721353290.528 [Startup:StartupSatComms:A] Running Loop=1 2024-07-19T01:41:30.932Z,1721353290.932 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-07-19T01:41:33.162Z,1721353293.162 [NavChartDb](INFO): # of records loaded: 90000 2024-07-19T01:41:40.185Z,1721353300.185 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2024-07-19T01:41:40.517Z,1721353300.517 [NavChartDb](INFO): # of records loaded: 95000 2024-07-19T01:41:40.726Z,1721353300.726 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:41:45.471Z,1721353305.471 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-07-19T01:41:45.471Z,1721353305.471 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-19T01:41:45.495Z,1721353305.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-19T01:41:45.889Z,1721353305.889 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-19T01:41:45.889Z,1721353305.889 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-07-19T01:41:48.866Z,1721353308.866 [NavChartDb](INFO): # of records loaded: 100000 2024-07-19T01:41:56.847Z,1721353316.847 [NavChartDb](INFO): # of records loaded: 105000 2024-07-19T01:41:58.767Z,1721353318.767 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:41:58.768Z,1721353318.768 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file). 2024-07-19T01:42:04.424Z,1721353324.424 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:42:06.071Z,1721353326.071 [NavChartDb](INFO): # of records loaded: 110000 2024-07-19T01:42:12.844Z,1721353332.844 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004127 2024-07-19T01:42:17.656Z,1721353337.656 [NavChartDb](INFO): # of records loaded: 115000 2024-07-19T01:42:18.563Z,1721353338.563 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:42:30.620Z,1721353350.620 [NavChartDb](INFO): # of records loaded: 120000 2024-07-19T01:42:30.798Z,1721353350.798 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-19T01:41:30.5Z 2024-07-19T01:42:30.798Z,1721353350.798 [Startup:StartupSatComms:A] Stopped 2024-07-19T01:42:30.798Z,1721353350.798 [Startup:StartupSatComms:B] Running Loop=1 2024-07-19T01:42:31.152Z,1721353351.152 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-07-19T01:42:32.521Z,1721353352.521 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:42:35.403Z,1721353355.403 [CommandExec](IMPORTANT): got command failComponent 2024-07-19T01:42:35.403Z,1721353355.403 [CommandExec](IMPORTANT): Failed components: 2024-07-19T01:42:35.403Z,1721353355.403 [CommandExec](IMPORTANT): No failed Components. 2024-07-19T01:42:39.051Z,1721353359.051 [CommandExec](IMPORTANT): got command get latitude 2024-07-19T01:42:39.052Z,1721353359.052 [CommandExec](FAULT): Element has no value 2024-07-19T01:42:40.723Z,1721353360.723 [CommandExec](IMPORTANT): got command ibit 2024-07-19T01:42:41.061Z,1721353361.061 [IBIT](IMPORTANT): Beginning Initiated BIT 2024-07-19T01:42:41.062Z,1721353361.062 [IBIT](IMPORTANT): Beginning control surface checks. 2024-07-19T01:42:41.073Z,1721353361.073 [CBIT](IMPORTANT): Beginning ground fault scan 2024-07-19T01:42:47.031Z,1721353367.031 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:42:49.658Z,1721353369.658 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:42:51.985Z,1721353371.985 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.003013 CHAN A1 (24V): -0.006728 CHAN A2 (12V): -0.004410 CHAN A3 (5V): -0.002710 CHAN B0 (3.3V): -0.000634 CHAN B1 (3.15aV): -0.001146 CHAN B2 (3.15bV): -0.001199 CHAN B3 (GND): -0.000212 OPEN: 0.003501 Full Scale: +/- 1 mA 2024-07-19T01:42:53.682Z,1721353373.682 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240719T012055/Courier0010.lzma 2024-07-19T01:42:54.069Z,1721353374.069 [NavChartDb](INFO): # of records loaded: 125000 2024-07-19T01:42:54.684Z,1721353374.684 [DataOverHttps](INFO): Moved sent file to Logs/20240719T012055/Courier0010.lzma.bak 2024-07-19T01:42:54.685Z,1721353374.685 [DataOverHttps](INFO): SBD MOMSN=21368470 2024-07-19T01:43:01.576Z,1721353381.576 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:43:10.678Z,1721353390.678 [NavChartDb](INFO): # of records loaded: 130000 2024-07-19T01:43:15.324Z,1721353395.324 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:43:23.006Z,1721353403.006 [NavChartDb](INFO): # of records loaded: 135000 2024-07-19T01:43:24.882Z,1721353404.882 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240719T013834/Courier0000.lzma 2024-07-19T01:43:25.884Z,1721353405.884 [DataOverHttps](INFO): Moved sent file to Logs/20240719T013834/Courier0000.lzma.bak 2024-07-19T01:43:25.884Z,1721353405.884 [DataOverHttps](INFO): SBD MOMSN=21368472 2024-07-19T01:43:29.880Z,1721353409.880 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:43:37.437Z,1721353417.437 [NavChartDb](INFO): # of records loaded: 140000 2024-07-19T01:43:44.429Z,1721353424.429 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:43:49.841Z,1721353429.841 [NavChartDb](INFO): # of records loaded: 145000 2024-07-19T01:43:59.001Z,1721353439.001 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-07-19T01:43:59.502Z,1721353439.502 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20240719T012055/Express0011.lzma 2024-07-19T01:44:00.504Z,1721353440.504 [DataOverHttps](INFO): Moved sent file to Logs/20240719T012055/Express0011.lzma.bak 2024-07-19T01:44:00.504Z,1721353440.504 [DataOverHttps](INFO): SBD MOMSN=21368475 2024-07-19T01:44:03.505Z,1721353443.505 [NavChartDb](INFO): # of records loaded: 150000 2024-07-19T01:44:13.519Z,1721353453.519 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-07-19T01:44:22.164Z,1721353462.164 [NavChartDb](INFO): # of records loaded: 155000 2024-07-19T01:44:26.924Z,1721353466.924 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-07-19T01:44:32.204Z,1721353472.204 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2024-07-19T01:44:32.589Z,1721353472.589 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2024-07-19T01:44:32.981Z,1721353472.981 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2024-07-19T01:44:32.981Z,1721353472.981 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2024-07-19T01:44:32.982Z,1721353472.982 [IBIT](IMPORTANT): Pressure:10.267482 PSI 2024-07-19T01:44:33.022Z,1721353473.022 [IBIT](IMPORTANT): Humidity:17.250641 % 2024-07-19T01:44:33.377Z,1721353473.377 [IBIT](IMPORTANT): Vehicle Pitch:-2.858396 degrees 2024-07-19T01:44:33.377Z,1721353473.377 [IBIT](IMPORTANT): Vehicle Roll:-6.149263 degrees 2024-07-19T01:44:33.377Z,1721353473.377 [IBIT](IMPORTANT): Vehicle Heading:283.703888 degrees 2024-07-19T01:44:33.799Z,1721353473.799 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2024-07-19T01:44:33.799Z,1721353473.799 [IBIT](IMPORTANT): buoyancyNeutral: 250.000015 cc 2024-07-19T01:44:33.799Z,1721353473.799 [IBIT](IMPORTANT): massDefault: 2.250000 cm 2024-07-19T01:44:33.799Z,1721353473.799 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2024-07-19T01:44:33.800Z,1721353473.800 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2024-07-19T01:44:33.800Z,1721353473.800 [IBIT](IMPORTANT): IBIT FAILED 2024-07-19T01:44:34.168Z,1721353474.168 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-19T01:42:30.8Z 2024-07-19T01:44:34.168Z,1721353474.168 [Startup:StartupSatComms:B] Stopped 2024-07-19T01:44:34.168Z,1721353474.168 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-07-19T01:44:34.168Z,1721353474.168 [Startup:StartupSatComms] Stopped 2024-07-19T01:44:34.168Z,1721353474.168 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-07-19T01:44:34.169Z,1721353474.169 [Startup](INFO): Completed Startup 2024-07-19T01:44:34.169Z,1721353474.169 [MissionManager](INFO): Startup is completed. 2024-07-19T01:44:34.170Z,1721353474.170 [MissionManager](INFO): Uninitializing Mission Startup 2024-07-19T01:44:34.170Z,1721353474.170 [Startup] Stopped 2024-07-19T01:44:34.178Z,1721353474.178 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-07-19T01:44:34.178Z,1721353474.178 [Startup:A.GoToSurface] Stopped 2024-07-19T01:44:34.178Z,1721353474.178 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-07-19T01:44:34.583Z,1721353474.583 [MissionManager](IMPORTANT): Started mission Default 2024-07-19T01:44:34.584Z,1721353474.584 [Default] Running Loop=1 2024-07-19T01:44:34.584Z,1721353474.584 [Default](DEBUG): Aggregate::initialize Default 2024-07-19T01:44:34.584Z,1721353474.584 [Default:B.GoToSurface] Running Loop=1 2024-07-19T01:44:34.584Z,1721353474.584 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-07-19T01:44:34.584Z,1721353474.584 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-07-19T01:44:34.584Z,1721353474.584 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-07-19T01:44:34.585Z,1721353474.585 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-07-19T01:44:34.585Z,1721353474.585 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-07-19T01:44:34.585Z,1721353474.585 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-07-19T01:44:34.595Z,1721353474.595 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-07-19T01:44:34.595Z,1721353474.595 [Default:A.Wait] Running Loop=1 2024-07-19T01:44:34.595Z,1721353474.595 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-07-19T01:44:39.047Z,1721353479.047 [DataOverHttps](INFO): Sending 923 bytes from file Logs/20240719T013834/Express0001.lzma 2024-07-19T01:44:39.860Z,1721353479.860 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-07-19T01:44:40.048Z,1721353480.048 [DataOverHttps](INFO): Moved sent file to Logs/20240719T013834/Express0001.lzma.bak 2024-07-19T01:44:40.048Z,1721353480.048 [DataOverHttps](INFO): SBD MOMSN=21368479 2024-07-19T01:44:44.902Z,1721353484.902 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:44:46.697Z,1721353486.697 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-07-19T01:44:46.697Z,1721353486.697 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-19T01:44:46.778Z,1721353486.778 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-19T01:44:47.199Z,1721353487.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-19T01:44:47.199Z,1721353487.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-07-19T01:44:47.945Z,1721353487.945 [Default:A.Wait](INFO): Done Waiting. 2024-07-19T01:44:47.945Z,1721353487.945 [Default:A.Wait] Stopped 2024-07-19T01:44:47.945Z,1721353487.945 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-07-19T01:44:48.323Z,1721353488.323 [Default:CheckIn] Running Loop=1 2024-07-19T01:44:48.323Z,1721353488.323 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-07-19T01:44:48.324Z,1721353488.324 [Default:CheckIn:Read_GPS] Running Loop=1 2024-07-19T01:44:48.791Z,1721353488.791 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-07-19T01:44:49.274Z,1721353489.274 [NavChartDb](INFO): # of records loaded: 160000 2024-07-19T01:45:05.148Z,1721353505.148 [NavChartDb](INFO): # of records loaded: 165000 2024-07-19T01:45:09.769Z,1721353509.769 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:45:38.102Z,1721353538.102 [NavChartDb](INFO): # of records loaded: 170000 2024-07-19T01:45:39.317Z,1721353539.317 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5MI61M.000 2024-07-19T01:45:39.331Z,1721353539.331 [NavChartDb](INFO): # of records loaded: 170330 2024-07-19T01:45:39.349Z,1721353539.349 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5MI61M.000 2024-07-19T01:45:39.378Z,1721353539.378 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:45:39.691Z,1721353539.691 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:45:46.122Z,1721353546.122 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:45:46.752Z,1721353546.752 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:45:47.469Z,1721353547.469 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1MF.000 2024-07-19T01:45:47.500Z,1721353547.500 [NavChartDb](INFO): # of records loaded: 4559 2024-07-19T01:45:47.503Z,1721353547.503 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1MF.000 2024-07-19T01:45:47.548Z,1721353547.548 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:45:47.792Z,1721353547.792 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:45:51.321Z,1721353551.321 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:45:54.186Z,1721353554.186 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:45:56.238Z,1721353556.238 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NF.000 2024-07-19T01:45:56.295Z,1721353556.295 [NavChartDb](INFO): # of records loaded: 4667 2024-07-19T01:45:56.297Z,1721353556.297 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NF.000 2024-07-19T01:45:56.322Z,1721353556.322 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:45:59.669Z,1721353559.669 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:46:06.760Z,1721353566.760 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:46:14.280Z,1721353574.280 [NavChartDb](INFO): # of records loaded: 10000 2024-07-19T01:46:26.292Z,1721353586.292 [NavChartDb](INFO): # of records loaded: 15000 2024-07-19T01:46:31.102Z,1721353591.102 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:46:37.635Z,1721353597.635 [NavChartDb](INFO): # of records loaded: 20000 2024-07-19T01:46:44.333Z,1721353604.333 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-07-19T01:46:45.860Z,1721353605.860 [NavChartDb](INFO): # of records loaded: 25000 2024-07-19T01:46:48.759Z,1721353608.759 [NavChartDb](INFO): # of records loaded: 30000 2024-07-19T01:46:53.782Z,1721353613.782 [NavChartDb](INFO): # of records loaded: 35000 2024-07-19T01:46:53.897Z,1721353613.897 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:46:58.375Z,1721353618.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2024-07-19T01:46:58.382Z,1721353618.382 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5. 2024-07-19T01:46:58.385Z,1721353618.385 [BPC1](INFO): Received data from all battery sticks. 2024-07-19T01:47:00.700Z,1721353620.700 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:47:01.420Z,1721353621.420 [NavChartDb](INFO): # of records loaded: 40000 2024-07-19T01:47:09.540Z,1721353629.540 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OF.000 2024-07-19T01:47:09.603Z,1721353629.603 [NavChartDb](INFO): # of records loaded: 42018 2024-07-19T01:47:09.608Z,1721353629.608 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OF.000 2024-07-19T01:47:09.622Z,1721353629.622 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:47:12.091Z,1721353632.091 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:47:20.103Z,1721353640.103 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:47:29.185Z,1721353649.185 [NavChartDb](INFO): # of records loaded: 10000 2024-07-19T01:47:36.999Z,1721353656.999 [NavChartDb](INFO): # of records loaded: 15000 2024-07-19T01:47:47.704Z,1721353667.704 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-07-19T01:47:47.704Z,1721353667.704 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-07-19T01:47:47.751Z,1721353667.751 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-07-19T01:47:47.764Z,1721353667.764 [NavChartDb](INFO): # of records loaded: 20000 2024-07-19T01:47:48.170Z,1721353668.170 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-07-19T01:47:48.170Z,1721353668.170 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-07-19T01:47:55.852Z,1721353675.852 [NavChartDb](INFO): # of records loaded: 25000 2024-07-19T01:48:03.849Z,1721353683.849 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,014802.00,A,4538.83231,N,08428.26085,W,1.283,0.00,190724,,,A*7C 2024-07-19T01:48:03.852Z,1721353683.852 [NAL9602](INFO): GPS fix at 20240719T014802: (45.647205, -84.471014) 2024-07-19T01:48:03.915Z,1721353683.915 [Default:CheckIn:Read_GPS] Stopped 2024-07-19T01:48:03.915Z,1721353683.915 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-07-19T01:48:04.123Z,1721353684.123 [NavChartDb](INFO): # of records loaded: 30000 2024-07-19T01:48:04.301Z,1721353684.301 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-07-19T01:48:11.425Z,1721353691.425 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:48:17.759Z,1721353697.759 [NavChartDb](INFO): # of records loaded: 35000 2024-07-19T01:48:19.258Z,1721353699.258 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20240719T013834/Courier0004.lzma 2024-07-19T01:48:20.260Z,1721353700.260 [DataOverHttps](INFO): Moved sent file to Logs/20240719T013834/Courier0004.lzma.bak 2024-07-19T01:48:20.260Z,1721353700.260 [DataOverHttps](INFO): SBD MOMSN=21368505 2024-07-19T01:48:27.432Z,1721353707.432 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:48:36.547Z,1721353716.547 [NAL9602](INFO): Not Powering down - fast GPS 2024-07-19T01:48:41.598Z,1721353721.598 [NavChartDb](INFO): # of records loaded: 40000 2024-07-19T01:48:46.595Z,1721353726.595 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI51M.000 2024-07-19T01:48:46.909Z,1721353726.909 [NavChartDb](INFO): # of records loaded: 40792 2024-07-19T01:48:46.931Z,1721353726.931 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI51M.000 2024-07-19T01:48:46.932Z,1721353726.932 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:48:47.063Z,1721353727.063 [Power24vConverter](INFO): Powering down. 2024-07-19T01:48:47.489Z,1721353727.489 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:48:59.647Z,1721353739.647 [DataOverHttps](INFO): Sending 968 bytes from file Logs/20240719T013834/Express0005.lzma 2024-07-19T01:49:00.582Z,1721353740.582 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:49:00.648Z,1721353740.648 [DataOverHttps](INFO): Moved sent file to Logs/20240719T013834/Express0005.lzma.bak 2024-07-19T01:49:00.648Z,1721353740.648 [DataOverHttps](INFO): SBD MOMSN=21368508 2024-07-19T01:49:14.048Z,1721353754.048 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:49:15.765Z,1721353755.765 [Default:CheckIn:Read_Iridium] Stopped 2024-07-19T01:49:15.765Z,1721353755.765 [Default:CheckIn:C.Wait] Running Loop=1 2024-07-19T01:49:15.765Z,1721353755.765 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-07-19T01:49:16.236Z,1721353756.236 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:49:18.393Z,1721353758.393 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OD.000 2024-07-19T01:49:18.504Z,1721353758.504 [NavChartDb](INFO): # of records loaded: 9682 2024-07-19T01:49:18.510Z,1721353758.510 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OD.000 2024-07-19T01:49:18.511Z,1721353758.511 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:49:19.976Z,1721353759.976 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:49:33.950Z,1721353773.950 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:49:44.723Z,1721353784.723 [NavChartDb](INFO): # of records loaded: 10000 2024-07-19T01:49:56.340Z,1721353796.340 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:49:56.347Z,1721353796.347 [NavChartDb](INFO): # of records loaded: 15000 2024-07-19T01:50:01.592Z,1721353801.592 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:50:04.892Z,1721353804.892 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1ND.000 2024-07-19T01:50:04.949Z,1721353804.949 [NavChartDb](INFO): # of records loaded: 19081 2024-07-19T01:50:04.963Z,1721353804.963 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1ND.000 2024-07-19T01:50:04.982Z,1721353804.982 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI60M.000 2024-07-19T01:50:07.766Z,1721353807.766 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI60M.000 2024-07-19T01:50:21.677Z,1721353821.677 [NavChartDb](INFO): # of records loaded: 5000 2024-07-19T01:50:37.253Z,1721353837.253 [NavChartDb](INFO): # of records loaded: 10000 2024-07-19T01:50:44.473Z,1721353844.473 [NavChartDb](INFO): # of records loaded: 15000 2024-07-19T01:50:54.301Z,1721353854.301 [NavChartDb](INFO): # of records loaded: 20000 2024-07-19T01:51:00.476Z,1721353860.476 [NavChartDb](INFO): # of records loaded: 25000 2024-07-19T01:51:09.659Z,1721353869.659 [NavChartDb](INFO): # of records loaded: 30000 2024-07-19T01:51:15.233Z,1721353875.233 [NavChartDb](INFO): # of records loaded: 35000 2024-07-19T01:51:21.698Z,1721353881.698 [NavChartDb](INFO): # of records loaded: 40000 2024-07-19T01:51:28.816Z,1721353888.816 [NavChartDb](INFO): # of records loaded: 45000 2024-07-19T01:51:32.028Z,1721353892.028 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI60M.000 2024-07-19T01:51:36.919Z,1721353896.919 [NavChartDb](INFO): # of records loaded: 50000 2024-07-19T01:51:45.896Z,1721353905.896 [NavChartDb](INFO): # of records loaded: 55000 2024-07-19T01:51:54.963Z,1721353914.963 [NavChartDb](INFO): # of records loaded: 60000 2024-07-19T01:51:58.375Z,1721353918.375 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2MI60M.000 2024-07-19T01:52:03.318Z,1721353923.318 [CommandExec](IMPORTANT): got command get latitude 2024-07-19T01:52:03.326Z,1721353923.326 [CommandExec](IMPORTANT): latitude 45.647205 arcdeg 2024-07-19T01:52:07.292Z,1721353927.292 [CommandExec](IMPORTANT): got command show variable distance 2024-07-19T01:52:07.295Z,1721353927.295 [CommandExec](IMPORTANT): distance_from_shore (unknown) 2024-07-19T01:52:07.295Z,1721353927.295 [CommandExec](IMPORTANT): fix_distance_made_good (unknown) 2024-07-19T01:52:07.296Z,1721353927.296 [CommandExec](IMPORTANT): fix_residual_distance (unknown) 2024-07-19T01:52:07.296Z,1721353927.296 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled (unknown) 2024-07-19T01:52:07.297Z,1721353927.297 [CommandExec](IMPORTANT): platform_distance_wrt_ground (unknown) 2024-07-19T01:52:07.322Z,1721353927.322 [CommandExec](IMPORTANT): platform_distance_wrt_sea_water (meter) 2024-07-19T01:52:07.392Z,1721353927.392 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool) 2024-07-19T01:52:07.392Z,1721353927.392 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.verbosity (count) 2024-07-19T01:52:07.393Z,1721353927.393 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1 (degree) 2024-07-19T01:52:07.393Z,1721353927.393 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1 (degree) 2024-07-19T01:52:07.393Z,1721353927.393 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2 (degree) 2024-07-19T01:52:07.394Z,1721353927.394 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2 (degree) 2024-07-19T01:52:07.394Z,1721353927.394 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3 (degree) 2024-07-19T01:52:07.395Z,1721353927.395 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3 (degree)