2024-07-18T13:31:47.874Z,1721309507.874 [Supervisor](DEBUG): Initializing supervisor.
2024-07-18T13:31:47.878Z,1721309507.878 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-18T13:31:47.879Z,1721309507.879 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-07-18T13:31:47.879Z,1721309507.879 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-18T13:31:47.880Z,1721309507.880 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-18T13:31:47.881Z,1721309507.881 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-07-18T13:31:47.885Z,1721309507.885 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-18T13:31:47.903Z,1721309507.903 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-18T13:31:47.904Z,1721309507.904 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-18T13:31:47.904Z,1721309507.904 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-07-18T13:31:47.908Z,1721309507.908 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-18T13:31:47.909Z,1721309507.909 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-18T13:31:47.910Z,1721309507.910 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-07-18T13:31:47.912Z,1721309507.912 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-18T13:31:47.913Z,1721309507.913 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-18T13:31:47.913Z,1721309507.913 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-07-18T13:31:47.917Z,1721309507.917 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-18T13:31:47.917Z,1721309507.917 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-18T13:31:47.921Z,1721309507.921 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-18T13:31:48.451Z,1721309508.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-18T13:31:48.453Z,1721309508.453 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-18T13:31:48.688Z,1721309508.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-18T13:31:48.690Z,1721309508.690 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-18T13:31:48.784Z,1721309508.784 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-18T13:31:49.044Z,1721309509.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-18T13:31:49.046Z,1721309509.046 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-18T13:31:49.138Z,1721309509.138 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-18T13:31:49.247Z,1721309509.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-18T13:31:49.249Z,1721309509.249 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-18T13:31:49.622Z,1721309509.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-18T13:31:49.623Z,1721309509.623 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-18T13:31:49.771Z,1721309509.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-18T13:31:49.773Z,1721309509.773 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-18T13:31:50.347Z,1721309510.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-18T13:31:50.348Z,1721309510.348 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-18T13:31:50.559Z,1721309510.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-18T13:31:50.561Z,1721309510.561 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-18T13:31:51.004Z,1721309511.004 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-18T13:31:51.005Z,1721309511.005 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-18T13:31:51.342Z,1721309511.342 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-18T13:31:51.343Z,1721309511.343 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-18T13:31:51.763Z,1721309511.763 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-18T13:31:51.934Z,1721309511.934 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-18T13:31:53.108Z,1721309513.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-18T13:31:53.110Z,1721309513.110 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-18T13:31:53.496Z,1721309513.496 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-18T13:31:53.498Z,1721309513.498 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-18T13:31:53.750Z,1721309513.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-18T13:31:53.752Z,1721309513.752 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-18T13:31:53.755Z,1721309513.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-18T13:31:53.887Z,1721309513.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-18T13:31:53.984Z,1721309513.984 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-18T13:31:54.138Z,1721309514.138 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-18T13:31:54.236Z,1721309514.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-18T13:31:54.515Z,1721309514.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-18T13:31:54.516Z,1721309514.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-18T13:31:54.636Z,1721309514.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-18T13:31:54.758Z,1721309514.758 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-18T13:31:54.882Z,1721309514.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-18T13:31:55.049Z,1721309515.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-18T13:31:55.161Z,1721309515.161 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-18T13:31:55.262Z,1721309515.262 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-18T13:31:55.273Z,1721309515.273 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-18T13:31:55.290Z,1721309515.290 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-18T13:31:55.290Z,1721309515.290 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-18T13:31:55.436Z,1721309515.436 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-18T13:31:55.437Z,1721309515.437 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-18T13:31:55.506Z,1721309515.506 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-18T13:31:55.568Z,1721309515.568 [VerticalControl] Loaded
2024-07-18T13:31:55.568Z,1721309515.568 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-18T13:31:55.571Z,1721309515.571 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-18T13:31:55.619Z,1721309515.619 [HorizontalControl] Loaded
2024-07-18T13:31:55.620Z,1721309515.620 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-18T13:31:55.622Z,1721309515.622 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-18T13:31:55.625Z,1721309515.625 [SpeedControl] Loaded
2024-07-18T13:31:55.625Z,1721309515.625 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-18T13:31:55.628Z,1721309515.628 [LoopControl](DEBUG): Construct LoopControl.
2024-07-18T13:31:55.628Z,1721309515.628 [LoopControl] Loaded
2024-07-18T13:31:55.629Z,1721309515.629 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-18T13:31:55.629Z,1721309515.629 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-18T13:31:55.631Z,1721309515.631 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-18T13:31:55.820Z,1721309515.820 [BuoyancyServo] Loaded
2024-07-18T13:31:55.821Z,1721309515.821 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-18T13:31:55.843Z,1721309515.843 [ElevatorServo] Loaded
2024-07-18T13:31:55.843Z,1721309515.843 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-18T13:31:55.864Z,1721309515.864 [MassServo] Loaded
2024-07-18T13:31:55.864Z,1721309515.864 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-18T13:31:55.884Z,1721309515.884 [RudderServo] Loaded
2024-07-18T13:31:55.885Z,1721309515.885 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-18T13:31:55.901Z,1721309515.901 [ThrusterHE] Loaded
2024-07-18T13:31:55.901Z,1721309515.901 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-18T13:31:55.902Z,1721309515.902 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-18T13:31:55.902Z,1721309515.902 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-18T13:31:55.973Z,1721309515.973 [DepthRateCalculator] Loaded
2024-07-18T13:31:55.973Z,1721309515.973 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-18T13:31:55.978Z,1721309515.978 [PitchRateCalculator] Loaded
2024-07-18T13:31:55.978Z,1721309515.978 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-18T13:31:55.988Z,1721309515.988 [SpeedCalculator] Loaded
2024-07-18T13:31:55.988Z,1721309515.988 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-18T13:31:55.992Z,1721309515.992 [YawRateCalculator] Loaded
2024-07-18T13:31:55.993Z,1721309515.993 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-18T13:31:56.011Z,1721309516.011 [ElevatorOffsetCalculator] Loaded
2024-07-18T13:31:56.011Z,1721309516.011 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-18T13:31:56.012Z,1721309516.012 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-18T13:31:56.013Z,1721309516.013 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-18T13:31:56.150Z,1721309516.150 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-18T13:31:56.150Z,1721309516.150 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-18T13:31:56.164Z,1721309516.164 [NavChart] Loaded
2024-07-18T13:31:56.164Z,1721309516.164 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-18T13:31:56.170Z,1721309516.170 [UniversalFixResidualReporter] Loaded
2024-07-18T13:31:56.170Z,1721309516.170 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-18T13:31:56.171Z,1721309516.171 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-18T13:31:56.171Z,1721309516.171 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-18T13:31:57.014Z,1721309517.014 [AHRS_M2] Loaded
2024-07-18T13:31:57.014Z,1721309517.014 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-18T13:31:57.368Z,1721309517.368 [BackseatComponent] Loaded
2024-07-18T13:31:57.369Z,1721309517.369 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-18T13:31:57.381Z,1721309517.381 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-18T13:31:57.381Z,1721309517.381 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2024-07-18T13:31:57.384Z,1721309517.384 [LcmUniversalReporter] Loaded
2024-07-18T13:31:57.384Z,1721309517.384 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-18T13:31:57.399Z,1721309517.399 [BioacousticsDataBridge] Loaded
2024-07-18T13:31:57.399Z,1721309517.399 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-18T13:31:58.452Z,1721309518.452 [BPC1] Loaded
2024-07-18T13:31:58.453Z,1721309518.453 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-18T13:31:58.592Z,1721309518.592 [DataOverHttps] Loaded
2024-07-18T13:31:58.592Z,1721309518.592 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-18T13:31:58.593Z,1721309518.593 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-18T13:31:58.593Z,1721309518.593 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2024-07-18T13:31:58.617Z,1721309518.617 [Depth_Keller] Loaded
2024-07-18T13:31:58.617Z,1721309518.617 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-18T13:31:58.622Z,1721309518.622 [DropWeight] Loaded
2024-07-18T13:31:58.622Z,1721309518.622 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-18T13:31:58.686Z,1721309518.686 [NAL9602] Loaded
2024-07-18T13:31:58.686Z,1721309518.686 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-18T13:31:58.717Z,1721309518.717 [Onboard] Loaded
2024-07-18T13:31:58.717Z,1721309518.717 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-18T13:31:58.718Z,1721309518.718 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-18T13:31:58.718Z,1721309518.718 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2024-07-18T13:31:58.733Z,1721309518.733 [Power24vConverter] Loaded
2024-07-18T13:31:58.733Z,1721309518.733 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-18T13:31:58.749Z,1721309518.749 [Radio_Surface] Loaded
2024-07-18T13:31:58.749Z,1721309518.749 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-18T13:31:58.750Z,1721309518.750 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-18T13:31:58.750Z,1721309518.750 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2024-07-18T13:31:58.766Z,1721309518.766 [Sonardyne_Nano] Loaded
2024-07-18T13:31:58.766Z,1721309518.766 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-18T13:31:58.767Z,1721309518.767 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-18T13:31:58.767Z,1721309518.767 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-18T13:31:58.849Z,1721309518.849 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-18T13:31:58.849Z,1721309518.849 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-18T13:31:59.067Z,1721309519.067 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-18T13:31:59.067Z,1721309519.067 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-18T13:31:59.112Z,1721309519.112 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-18T13:31:59.113Z,1721309519.113 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-18T13:31:59.339Z,1721309519.339 [CTD_Seabird] Loaded
2024-07-18T13:31:59.339Z,1721309519.339 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-18T13:31:59.340Z,1721309519.340 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-18T13:31:59.340Z,1721309519.340 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2024-07-18T13:31:59.363Z,1721309519.363 [PAR_Licor] Loaded
2024-07-18T13:31:59.363Z,1721309519.363 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-18T13:31:59.410Z,1721309519.410 [WetLabsBB2FL] Loaded
2024-07-18T13:31:59.410Z,1721309519.410 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-18T13:31:59.411Z,1721309519.411 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-18T13:31:59.412Z,1721309519.412 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2024-07-18T13:31:59.412Z,1721309519.412 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-18T13:31:59.413Z,1721309519.413 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-18T13:31:59.812Z,1721309519.812 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-18T13:31:59.812Z,1721309519.812 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-18T13:31:59.987Z,1721309519.987 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-18T13:31:59.997Z,1721309519.997 [SBIT] Loaded
2024-07-18T13:31:59.997Z,1721309519.997 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-18T13:31:59.000Z,1721309520.000 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-18T13:32:00.012Z,1721309520.012 [IBIT] Loaded
2024-07-18T13:32:00.013Z,1721309520.013 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-18T13:32:00.019Z,1721309520.019 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-18T13:32:00.125Z,1721309520.125 [CBIT] Loaded
2024-07-18T13:32:00.125Z,1721309520.125 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-18T13:32:00.125Z,1721309520.125 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-18T13:32:00.132Z,1721309520.132 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-18T13:32:00.135Z,1721309520.135 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-18T13:32:00.147Z,1721309520.147 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-18T13:32:00.148Z,1721309520.148 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-18T13:32:00.148Z,1721309520.148 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2024-07-18T13:32:00.153Z,1721309520.153 [Supervisor](INFO): Main Thread ID is 830
2024-07-18T13:32:00.153Z,1721309520.153 [Supervisor](DEBUG): Running supervisor.
2024-07-18T13:32:00.153Z,1721309520.153 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2024-07-18T13:32:00.154Z,1721309520.154 [CommandExec](INFO): Initializing the command executive.
2024-07-18T13:32:00.155Z,1721309520.155 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2024-07-18T13:32:00.157Z,1721309520.157 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2024-07-18T13:32:00.158Z,1721309520.158 [controlThread](DEBUG): Initializing ControlThread
2024-07-18T13:32:00.159Z,1721309520.159 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-18T13:32:00.160Z,1721309520.160 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-18T13:32:00.161Z,1721309520.161 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-18T13:32:00.161Z,1721309520.161 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-18T13:32:00.163Z,1721309520.163 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-18T13:32:00.163Z,1721309520.163 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-18T13:32:00.163Z,1721309520.163 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-18T13:32:00.164Z,1721309520.164 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-18T13:32:00.164Z,1721309520.164 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-18T13:32:00.165Z,1721309520.165 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-18T13:32:00.165Z,1721309520.165 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-18T13:32:00.173Z,1721309520.173 [SBIT](INFO): Initialize SBIT Component.
2024-07-18T13:32:00.173Z,1721309520.173 [SBIT](IMPORTANT): git: 2024-07-18
2024-07-18T13:32:00.174Z,1721309520.174 [SBIT](INFO): git hash: e777c895b2d7d743d45b75a88845e2c8d3f60819
2024-07-18T13:32:00.174Z,1721309520.174 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-18T13:32:00.175Z,1721309520.175 [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-18T13:32:00.176Z,1721309520.176 [SBIT](INFO): Beginning SBIT in 110.000000 seconds.
2024-07-18T13:32:00.177Z,1721309520.177 [IBIT](INFO): Initialize IBIT Component.
2024-07-18T13:32:00.178Z,1721309520.178 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-18T13:32:00.178Z,1721309520.178 [logger ThreadHandler](INFO): Handler Thread ID is 933
2024-07-18T13:32:00.181Z,1721309520.181 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2024-07-18T13:32:00.189Z,1721309520.189 [CBIT](DEBUG): Initialized mux pins.
2024-07-18T13:32:00.189Z,1721309520.189 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-18T13:32:00.201Z,1721309520.201 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2024-07-18T13:32:00.203Z,1721309520.203 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-18T13:32:00.209Z,1721309520.209 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2024-07-18T13:32:00.213Z,1721309520.213 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-18T13:32:00.223Z,1721309520.223 [CBIT](FAULT): Found backtrace file:
1721275169.481881 [NavChartDb]: SIGSEGV: address not mapped to object
1721275169.481881 [NavChartDb]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3ca8]
./bin/LRAUV(_ZN10NavChartDb10addEncDataEPNS_7EncInfoE+0x130) [0x14b618]
./bin/LRAUV(_ZN10NavChartDb3runEv+0x110) [0x14d8e4]
./bin/LRAUV(_ZN9Component15executeRunStateEv+0x5c) [0xeeec8]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1b36f4]

1721276070.405522 [NavChartDb]: SIGSEGV: address not mapped to object
1721276070.405522 [NavChartDb]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3ca8]
./bin/LRAUV(_ZN10NavChartDb10addEncDataEPNS_7EncInfoE+0x130) [0x14b618]
./bin/LRAUV(_ZN10NavChartDb3runEv+0x110) [0x14d8e4]
./bin/LRAUV(_ZN9Component15executeRunStateEv+0x5c) [0xeeec8]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1b36f4]

1721276971.037960 [NavChartDb]: SIGSEGV: address not mapped to object
1721276971.037960 [NavChartDb]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b3ca8]
./bin/LRAUV(_ZN10NavChartDb10addEncDataEPNS_7EncInfoE+0x130) [0x14b618]

2024-07-18T13:32:00.226Z,1721309520.226 [CBIT](FAULT): Renaming backtrace file to Data/backtrace9
2024-07-18T13:32:00.226Z,1721309520.226 [CBIT](DEBUG): Initializing heartbeat.
2024-07-18T13:32:00.243Z,1721309520.243 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2024-07-18T13:32:00.253Z,1721309520.253 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 938
2024-07-18T13:32:00.254Z,1721309520.254 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-18T13:32:00.259Z,1721309520.259 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 940
2024-07-18T13:32:00.265Z,1721309520.265 [WetLabsBB2FL](INFO): Powering up
2024-07-18T13:32:00.266Z,1721309520.266 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 942
2024-07-18T13:32:00.273Z,1721309520.273 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2024-07-18T13:32:00.277Z,1721309520.277 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:32:00.278Z,1721309520.278 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000
2024-07-18T13:32:00.279Z,1721309520.279 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:32:00.280Z,1721309520.280 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000
2024-07-18T13:32:00.280Z,1721309520.280 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:32:00.282Z,1721309520.282 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000
2024-07-18T13:32:00.282Z,1721309520.282 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:32:00.284Z,1721309520.284 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000
2024-07-18T13:32:00.284Z,1721309520.284 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:32:00.286Z,1721309520.286 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000
2024-07-18T13:32:00.286Z,1721309520.286 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:32:00.287Z,1721309520.287 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000
2024-07-18T13:32:00.287Z,1721309520.287 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:32:00.289Z,1721309520.289 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MF.000
2024-07-18T13:32:00.289Z,1721309520.289 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:32:00.291Z,1721309520.291 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5MI61M.000
2024-07-18T13:32:00.291Z,1721309520.291 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:32:00.292Z,1721309520.292 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NG.000
2024-07-18T13:32:00.293Z,1721309520.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:32:00.294Z,1721309520.294 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MG.000
2024-07-18T13:32:00.294Z,1721309520.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:32:00.296Z,1721309520.296 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NH.000
2024-07-18T13:32:00.297Z,1721309520.297 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-18T13:32:00.297Z,1721309520.297 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-18T13:32:00.337Z,1721309520.337 [CBIT](DEBUG): Backplane powered.
2024-07-18T13:32:00.337Z,1721309520.337 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-18T13:32:00.338Z,1721309520.338 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-18T13:32:00.338Z,1721309520.338 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-18T13:32:00.349Z,1721309520.349 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-18T13:32:00.377Z,1721309520.377 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:32:00.409Z,1721309520.409 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2024-07-18T13:32:00.414Z,1721309520.414 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-18T13:32:00.448Z,1721309520.448 [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-18T13:32:00.449Z,1721309520.449 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-18T13:32:00.449Z,1721309520.449 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-18T13:32:00.457Z,1721309520.457 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-18T13:32:00.649Z,1721309520.649 [Radio_Surface](INFO): Powering up
2024-07-18T13:32:00.744Z,1721309520.744 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2024-07-18T13:32:00.758Z,1721309520.758 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-18T13:32:00.759Z,1721309520.759 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-18T13:32:00.774Z,1721309520.774 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-18T13:32:00.798Z,1721309520.798 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-18T13:32:00.801Z,1721309520.801 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-18T13:32:00.833Z,1721309520.833 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-18T13:32:00.836Z,1721309520.836 [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-18T13:32:00.849Z,1721309520.849 [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-18T13:32:00.857Z,1721309520.857 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-18T13:32:01.012Z,1721309521.012 [Power24vConverter](INFO): Powering up.
2024-07-18T13:32:01.017Z,1721309521.017 [Sonardyne_Nano](INFO): Initializing.
2024-07-18T13:32:01.071Z,1721309521.071 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-18T13:32:01.077Z,1721309521.077 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-18T13:32:01.078Z,1721309521.078 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-18T13:32:01.085Z,1721309521.085 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-18T13:32:01.086Z,1721309521.086 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-18T13:32:01.093Z,1721309521.093 [MassServo](DEBUG): Initializing MassServo.
2024-07-18T13:32:01.094Z,1721309521.094 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-18T13:32:01.101Z,1721309521.101 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-18T13:32:01.102Z,1721309521.102 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-18T13:32:01.141Z,1721309521.141 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-18T13:32:02.373Z,1721309522.373 [WetLabsBB2FL](INFO): Powering down
2024-07-18T13:32:03.284Z,1721309523.284 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-18T13:32:04.522Z,1721309524.522 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-18T13:32:06.802Z,1721309526.802 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-18T13:32:10.349Z,1721309530.349 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:32:22.230Z,1721309542.230 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:32:28.746Z,1721309548.746 [NAL9602](INFO): Powering up NAL9602
2024-07-18T13:32:33.405Z,1721309553.405 [NavChartDb](INFO): # of records loaded: 10000
2024-07-18T13:32:39.654Z,1721309559.654 [NAL9602](INFO): NAL9602 initialized
2024-07-18T13:32:43.615Z,1721309563.615 [NavChartDb](INFO): # of records loaded: 15000
2024-07-18T13:32:52.827Z,1721309572.827 [NavChartDb](INFO): # of records loaded: 20000
2024-07-18T13:33:02.107Z,1721309582.107 [NavChartDb](INFO): # of records loaded: 25000
2024-07-18T13:33:07.528Z,1721309587.528 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T13:33:09.612Z,1721309589.612 [NavChartDb](INFO): # of records loaded: 30000
2024-07-18T13:33:16.353Z,1721309596.353 [NavChartDb](INFO): # of records loaded: 35000
2024-07-18T13:33:21.667Z,1721309601.667 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T13:33:24.945Z,1721309604.945 [NavChartDb](INFO): # of records loaded: 40000
2024-07-18T13:33:31.990Z,1721309611.990 [NavChartDb](INFO): # of records loaded: 45000
2024-07-18T13:33:35.811Z,1721309615.811 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-18T13:33:39.328Z,1721309619.328 [NavChartDb](INFO): # of records loaded: 50000
2024-07-18T13:33:46.994Z,1721309626.994 [NavChartDb](INFO): # of records loaded: 55000
2024-07-18T13:33:50.792Z,1721309630.792 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-18T13:33:50.796Z,1721309630.796 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-18T13:33:54.560Z,1721309634.560 [NavChartDb](INFO): # of records loaded: 60000
2024-07-18T13:34:01.727Z,1721309641.727 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004538
CHAN A1 (24V): -0.006272
CHAN A2 (12V): -0.004330
CHAN A3 (5V): -0.003102
CHAN B0 (3.3V): -0.001242
CHAN B1 (3.15aV): -0.000908
CHAN B2 (3.15bV): -0.000610
CHAN B3 (GND): -0.000227
OPEN: -0.004550
Full Scale: +/- 1 mA
2024-07-18T13:34:02.514Z,1721309642.514 [NavChartDb](INFO): # of records loaded: 65000
2024-07-18T13:34:02.880Z,1721309642.880 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-07-18T13:34:10.099Z,1721309650.099 [NavChartDb](INFO): # of records loaded: 70000
2024-07-18T13:34:17.718Z,1721309657.718 [NavChartDb](INFO): # of records loaded: 75000
2024-07-18T13:34:25.714Z,1721309665.714 [NavChartDb](INFO): # of records loaded: 80000
2024-07-18T13:34:34.488Z,1721309674.488 [NavChartDb](INFO): # of records loaded: 85000
2024-07-18T13:34:42.406Z,1721309682.406 [NavChartDb](INFO): # of records loaded: 90000
2024-07-18T13:34:44.947Z,1721309684.947 [SBIT](IMPORTANT): SBIT PASSED
2024-07-18T13:34:44.947Z,1721309684.947 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-18T13:34:44.948Z,1721309684.948 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;

2024-07-18T13:34:44.948Z,1721309684.948 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;

2024-07-18T13:34:44.953Z,1721309684.953 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2024-07-18T13:34:44.953Z,1721309684.953 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter;

2024-07-18T13:34:44.953Z,1721309684.953 [SBIT](IMPORTANT): VerticalControl.massDefault=2.25 centimeter;

2024-07-18T13:34:45.343Z,1721309685.343 [MissionManager](IMPORTANT): Started mission Startup
2024-07-18T13:34:45.343Z,1721309685.343 [Startup] Running Loop=1
2024-07-18T13:34:45.343Z,1721309685.343 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-18T13:34:45.343Z,1721309685.343 [Startup:A.GoToSurface] Running Loop=1
2024-07-18T13:34:45.343Z,1721309685.343 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-18T13:34:45.344Z,1721309685.344 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-18T13:34:45.344Z,1721309685.344 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-18T13:34:45.344Z,1721309685.344 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-18T13:34:45.352Z,1721309685.352 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-18T13:34:45.361Z,1721309685.361 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-18T13:34:45.364Z,1721309685.364 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-18T13:34:45.375Z,1721309685.375 [Startup:StartupSatComms] Running Loop=1
2024-07-18T13:34:45.375Z,1721309685.375 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-18T13:34:45.375Z,1721309685.375 [Startup:StartupSatComms:A] Running Loop=1
2024-07-18T13:34:45.738Z,1721309685.738 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-18T13:34:50.079Z,1721309690.079 [NavChartDb](INFO): # of records loaded: 95000
2024-07-18T13:34:58.017Z,1721309698.017 [NavChartDb](INFO): # of records loaded: 100000
2024-07-18T13:35:00.282Z,1721309700.282 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-18T13:35:00.282Z,1721309700.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-18T13:35:00.305Z,1721309700.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-18T13:35:00.707Z,1721309700.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-18T13:35:00.707Z,1721309700.707 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-18T13:35:05.755Z,1721309705.755 [NavChartDb](INFO): # of records loaded: 105000
2024-07-18T13:35:10.498Z,1721309710.498 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-18T13:35:13.797Z,1721309713.797 [NavChartDb](INFO): # of records loaded: 110000
2024-07-18T13:35:24.331Z,1721309724.331 [NavChartDb](INFO): # of records loaded: 115000
2024-07-18T13:35:27.583Z,1721309727.583 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004416
2024-07-18T13:35:33.971Z,1721309733.971 [NavChartDb](INFO): # of records loaded: 120000
2024-07-18T13:35:45.614Z,1721309745.614 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-18T13:34:45.4Z
2024-07-18T13:35:45.614Z,1721309745.614 [Startup:StartupSatComms:A] Stopped
2024-07-18T13:35:45.614Z,1721309745.615 [Startup:StartupSatComms:B] Running Loop=1
2024-07-18T13:35:45.954Z,1721309745.954 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-18T13:35:53.211Z,1721309753.211 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:35:57.906Z,1721309757.906 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-18T13:35:58.406Z,1721309758.406 [NavChartDb](INFO): # of records loaded: 125000
2024-07-18T13:36:09.713Z,1721309769.713 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240718T133147/Courier0000.lzma
2024-07-18T13:36:10.714Z,1721309770.714 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Courier0000.lzma.bak
2024-07-18T13:36:10.715Z,1721309770.715 [DataOverHttps](INFO): SBD MOMSN=21363020
2024-07-18T13:36:26.604Z,1721309786.604 [NavChartDb](INFO): # of records loaded: 130000
2024-07-18T13:36:41.188Z,1721309801.188 [NavChartDb](INFO): # of records loaded: 135000
2024-07-18T13:36:45.954Z,1721309805.954 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-18T13:35:45.6Z
2024-07-18T13:36:45.954Z,1721309805.954 [Startup:StartupSatComms:B] Stopped
2024-07-18T13:36:45.954Z,1721309805.954 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-18T13:36:45.954Z,1721309805.954 [Startup:StartupSatComms] Stopped
2024-07-18T13:36:45.954Z,1721309805.954 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-18T13:36:45.955Z,1721309805.955 [Startup](INFO): Completed Startup
2024-07-18T13:36:45.955Z,1721309805.955 [MissionManager](INFO): Startup is completed.
2024-07-18T13:36:45.955Z,1721309805.955 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-18T13:36:45.955Z,1721309805.955 [Startup] Stopped
2024-07-18T13:36:45.956Z,1721309805.956 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-18T13:36:45.956Z,1721309805.956 [Startup:A.GoToSurface] Stopped
2024-07-18T13:36:45.956Z,1721309805.956 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-18T13:36:46.298Z,1721309806.298 [MissionManager](IMPORTANT): Started mission Default
2024-07-18T13:36:46.298Z,1721309806.298 [Default] Running Loop=1
2024-07-18T13:36:46.298Z,1721309806.298 [Default](DEBUG): Aggregate::initialize Default
2024-07-18T13:36:46.298Z,1721309806.298 [Default:B.GoToSurface] Running Loop=1
2024-07-18T13:36:46.298Z,1721309806.298 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-18T13:36:46.298Z,1721309806.298 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-18T13:36:46.299Z,1721309806.299 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-18T13:36:46.299Z,1721309806.299 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-18T13:36:46.299Z,1721309806.299 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-18T13:36:46.299Z,1721309806.299 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-18T13:36:46.300Z,1721309806.300 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-18T13:36:46.300Z,1721309806.300 [Default:A.Wait] Running Loop=1
2024-07-18T13:36:46.300Z,1721309806.300 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-18T13:36:52.840Z,1721309812.840 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20240718T034537/Express0302.lzma
2024-07-18T13:36:52.996Z,1721309812.996 [NavChartDb](INFO): # of records loaded: 140000
2024-07-18T13:36:53.843Z,1721309813.843 [DataOverHttps](INFO): Moved sent file to Logs/20240718T034537/Express0302.lzma.bak
2024-07-18T13:36:53.843Z,1721309813.843 [DataOverHttps](INFO): SBD MOMSN=21363023
2024-07-18T13:36:59.696Z,1721309819.696 [Default:A.Wait](INFO): Done Waiting.
2024-07-18T13:36:59.696Z,1721309819.696 [Default:A.Wait] Stopped
2024-07-18T13:36:59.696Z,1721309819.696 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-18T13:37:00.064Z,1721309820.064 [Default:CheckIn] Running Loop=1
2024-07-18T13:37:00.064Z,1721309820.064 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-18T13:37:00.064Z,1721309820.064 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-18T13:37:00.463Z,1721309820.463 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-18T13:37:07.362Z,1721309827.362 [NavChartDb](INFO): # of records loaded: 145000
2024-07-18T13:37:18.445Z,1721309838.445 [NavChartDb](INFO): # of records loaded: 150000
2024-07-18T13:37:28.572Z,1721309848.572 [NavChartDb](INFO): # of records loaded: 155000
2024-07-18T13:37:33.677Z,1721309853.677 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-18T13:37:39.297Z,1721309859.297 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-18T13:37:42.838Z,1721309862.838 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-18T13:37:44.242Z,1721309864.242 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:37:45.967Z,1721309865.967 [NavChartDb](INFO): # of records loaded: 160000
2024-07-18T13:38:01.454Z,1721309881.454 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-07-18T13:38:01.454Z,1721309881.454 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-18T13:38:01.503Z,1721309881.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-18T13:38:01.875Z,1721309881.875 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-18T13:38:01.875Z,1721309881.875 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-07-18T13:38:06.792Z,1721309886.792 [NavChartDb](INFO): # of records loaded: 165000
2024-07-18T13:38:19.814Z,1721309899.814 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-18T13:38:19.982Z,1721309899.982 [CommandExec](IMPORTANT): got command show variable distance
2024-07-18T13:38:19.985Z,1721309899.985 [CommandExec](IMPORTANT): distance_from_shore (unknown)
2024-07-18T13:38:19.986Z,1721309899.986 [CommandExec](IMPORTANT): fix_distance_made_good (unknown)
2024-07-18T13:38:19.986Z,1721309899.986 [CommandExec](IMPORTANT): fix_residual_distance (unknown)
2024-07-18T13:38:19.987Z,1721309899.987 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled (unknown)
2024-07-18T13:38:19.988Z,1721309899.988 [CommandExec](IMPORTANT): platform_distance_wrt_ground (unknown)
2024-07-18T13:38:19.989Z,1721309899.989 [CommandExec](IMPORTANT): platform_distance_wrt_sea_water (meter)
2024-07-18T13:38:20.004Z,1721309900.004 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool)
2024-07-18T13:38:20.004Z,1721309900.004 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.verbosity (count)
2024-07-18T13:38:20.005Z,1721309900.005 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1 (degree)
2024-07-18T13:38:20.005Z,1721309900.005 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1 (degree)
2024-07-18T13:38:20.006Z,1721309900.006 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2 (degree)
2024-07-18T13:38:20.006Z,1721309900.006 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2 (degree)
2024-07-18T13:38:20.006Z,1721309900.006 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3 (degree)
2024-07-18T13:38:20.007Z,1721309900.007 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3 (degree)
2024-07-18T13:38:20.007Z,1721309900.007 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4 (degree)
2024-07-18T13:38:20.007Z,1721309900.007 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4 (degree)
2024-07-18T13:38:20.008Z,1721309900.008 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5 (degree)
2024-07-18T13:38:20.008Z,1721309900.008 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5 (degree)
2024-07-18T13:38:20.008Z,1721309900.008 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex6 (degree)
2024-07-18T13:38:20.013Z,1721309900.013 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex6 (degree)
2024-07-18T13:38:20.013Z,1721309900.013 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex7 (degree)
2024-07-18T13:38:20.014Z,1721309900.014 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex7 (degree)
2024-07-18T13:38:20.014Z,1721309900.014 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex8 (degree)
2024-07-18T13:38:20.014Z,1721309900.014 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex8 (degree)
2024-07-18T13:38:20.015Z,1721309900.015 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex9 (degree)
2024-07-18T13:38:20.015Z,1721309900.015 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex9 (degree)
2024-07-18T13:38:20.015Z,1721309900.015 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex10 (degree)
2024-07-18T13:38:20.016Z,1721309900.016 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex10 (degree)
2024-07-18T13:38:20.034Z,1721309900.034 [CommandExec](IMPORTANT): LineCapture.rolloutDistance (meter)
2024-07-18T13:38:20.148Z,1721309900.148 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter)
2024-07-18T13:38:20.226Z,1721309900.226 [CommandExec](IMPORTANT): GobyModem.maxDistance (kilometer)
2024-07-18T13:38:20.248Z,1721309900.248 [CommandExec](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2024-07-18T13:38:20.302Z,1721309900.302 [CommandExec](IMPORTANT): SpeedCalculator.platform_distance_wrt_sea_water (meter)
2024-07-18T13:38:20.304Z,1721309900.304 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_distance_made_good (meter)
2024-07-18T13:38:20.309Z,1721309900.309 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_distance (meter)
2024-07-18T13:38:20.313Z,1721309900.313 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_percent_distance_traveled (percent)
2024-07-18T13:38:20.314Z,1721309900.314 [CommandExec](IMPORTANT): NavChart.distance_from_shore (meter)
2024-07-18T13:38:20.381Z,1721309900.381 [CommandExec](IMPORTANT): NavChartDb.closestDistance (meter)
2024-07-18T13:38:20.382Z,1721309900.382 [CommandExec](IMPORTANT): NavChartDb.nextDistance (meter)
2024-07-18T13:38:30.958Z,1721309910.958 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-18T13:38:35.404Z,1721309915.404 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T13:38:42.678Z,1721309922.678 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-18T13:38:42.684Z,1721309922.684 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2024-07-18T13:38:42.687Z,1721309922.687 [BPC1](INFO): Received data from all battery sticks.
2024-07-18T13:38:45.450Z,1721309925.450 [NavChartDb](INFO): # of records loaded: 170000
2024-07-18T13:38:48.109Z,1721309928.109 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:38:48.161Z,1721309928.161 [NavChartDb](INFO): # of records loaded: 170330
2024-07-18T13:38:48.218Z,1721309928.218 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5MI61M.000
2024-07-18T13:38:48.219Z,1721309928.219 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:38:48.430Z,1721309928.430 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:38:48.541Z,1721309928.541 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:38:48.639Z,1721309928.639 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:38:49.266Z,1721309929.266 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:38:49.362Z,1721309929.362 [NavChartDb](INFO): # of records loaded: 153
2024-07-18T13:38:49.364Z,1721309929.364 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NH.000
2024-07-18T13:38:49.365Z,1721309929.365 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:38:49.692Z,1721309929.692 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:38:51.279Z,1721309931.279 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-18T13:38:51.279Z,1721309931.279 [CommandExec](IMPORTANT): NavChart.distance_from_shore nan m
2024-07-18T13:38:54.227Z,1721309934.227 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:38:54.898Z,1721309934.898 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:38:55.512Z,1721309935.512 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:38:55.532Z,1721309935.532 [NavChartDb](INFO): # of records loaded: 2821
2024-07-18T13:38:55.555Z,1721309935.555 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1MG.000
2024-07-18T13:38:55.578Z,1721309935.578 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:38:55.718Z,1721309935.718 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:38:55.797Z,1721309935.797 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:38:55.806Z,1721309935.806 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:38:56.318Z,1721309936.318 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:38:56.347Z,1721309936.347 [NavChartDb](INFO): # of records loaded: 313
2024-07-18T13:38:56.349Z,1721309936.349 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NG.000
2024-07-18T13:38:56.379Z,1721309936.379 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:38:56.628Z,1721309936.628 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:39:03.078Z,1721309943.078 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:39:03.641Z,1721309943.641 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:39:04.288Z,1721309944.288 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:39:04.329Z,1721309944.329 [NavChartDb](INFO): # of records loaded: 4559
2024-07-18T13:39:04.331Z,1721309944.331 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1MF.000
2024-07-18T13:39:04.371Z,1721309944.371 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:39:04.612Z,1721309944.612 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:39:08.088Z,1721309948.088 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:39:10.902Z,1721309950.902 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:39:12.216Z,1721309952.216 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:39:12.290Z,1721309952.290 [NavChartDb](INFO): # of records loaded: 4667
2024-07-18T13:39:12.292Z,1721309952.292 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NF.000
2024-07-18T13:39:12.305Z,1721309952.305 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:39:14.115Z,1721309954.115 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:39:22.595Z,1721309962.595 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:39:32.255Z,1721309972.255 [NavChartDb](INFO): # of records loaded: 10000
2024-07-18T13:39:35.104Z,1721309975.104 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-18T13:39:35.109Z,1721309975.109 [CommandExec](IMPORTANT): NavChart.distance_from_shore nan m
2024-07-18T13:39:41.012Z,1721309981.012 [NavChartDb](INFO): # of records loaded: 15000
2024-07-18T13:39:46.095Z,1721309986.095 [NavChartDb](INFO): # of records loaded: 20000
2024-07-18T13:39:52.203Z,1721309992.203 [NavChartDb](INFO): # of records loaded: 25000
2024-07-18T13:39:58.888Z,1721309998.888 [NavChartDb](INFO): # of records loaded: 30000
2024-07-18T13:40:09.390Z,1721310009.390 [NavChartDb](INFO): # of records loaded: 35000
2024-07-18T13:40:09.588Z,1721310009.588 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:40:19.455Z,1721310019.455 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:40:20.355Z,1721310020.355 [NavChartDb](INFO): # of records loaded: 40000
2024-07-18T13:40:26.772Z,1721310026.772 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:40:26.829Z,1721310026.829 [NavChartDb](INFO): # of records loaded: 42018
2024-07-18T13:40:26.834Z,1721310026.834 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OF.000
2024-07-18T13:40:26.853Z,1721310026.853 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:40:28.658Z,1721310028.658 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:40:39.658Z,1721310039.658 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:40:48.445Z,1721310048.445 [NavChartDb](INFO): # of records loaded: 10000
2024-07-18T13:40:56.599Z,1721310056.599 [NavChartDb](INFO): # of records loaded: 15000
2024-07-18T13:41:02.549Z,1721310062.549 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-07-18T13:41:02.549Z,1721310062.549 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-18T13:41:02.568Z,1721310062.568 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-18T13:41:02.973Z,1721310062.973 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-18T13:41:02.973Z,1721310062.973 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-07-18T13:41:04.296Z,1721310064.296 [NavChartDb](INFO): # of records loaded: 20000
2024-07-18T13:41:08.090Z,1721310068.090 [CommandExec](IMPORTANT): got command get latitude
2024-07-18T13:41:08.090Z,1721310068.090 [CommandExec](FAULT): Element has no value
2024-07-18T13:41:15.865Z,1721310075.865 [NavChartDb](INFO): # of records loaded: 25000
2024-07-18T13:41:23.730Z,1721310083.730 [NavChartDb](INFO): # of records loaded: 30000
2024-07-18T13:41:28.596Z,1721310088.596 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:41:32.290Z,1721310092.290 [NavChartDb](INFO): # of records loaded: 35000
2024-07-18T13:41:42.057Z,1721310102.057 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:41:52.901Z,1721310112.901 [CommandExec](IMPORTANT): got command ibit
2024-07-18T13:41:53.131Z,1721310113.131 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-07-18T13:41:53.131Z,1721310113.131 [IBIT](IMPORTANT): Beginning control surface checks.
2024-07-18T13:41:53.135Z,1721310113.135 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-18T13:41:54.127Z,1721310114.127 [NavChartDb](INFO): # of records loaded: 40000
2024-07-18T13:41:56.548Z,1721310116.548 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:41:56.679Z,1721310116.679 [NavChartDb](INFO): # of records loaded: 40792
2024-07-18T13:41:56.698Z,1721310116.698 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI51M.000
2024-07-18T13:41:56.699Z,1721310116.699 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:41:57.115Z,1721310117.115 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:42:01.966Z,1721310121.966 [Power24vConverter](INFO): Powering down.
2024-07-18T13:42:04.008Z,1721310124.008 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003165
CHAN A1 (24V): -0.007386
CHAN A2 (12V): -0.005013
CHAN A3 (5V): -0.002324
CHAN B0 (3.3V): -0.001569
CHAN B1 (3.15aV): -0.000963
CHAN B2 (3.15bV): -0.000704
CHAN B3 (GND): -0.000121
OPEN: 0.003338
Full Scale: +/- 1 mA
2024-07-18T13:42:04.922Z,1721310124.922 [CommandExec](IMPORTANT): got command strobe off
2024-07-18T13:42:04.922Z,1721310124.922 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-18T13:42:05.348Z,1721310125.348 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:42:14.998Z,1721310134.998 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:42:16.200Z,1721310136.200 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:42:17.855Z,1721310137.855 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:42:17.991Z,1721310137.991 [NavChartDb](INFO): # of records loaded: 9682
2024-07-18T13:42:17.993Z,1721310137.993 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OD.000
2024-07-18T13:42:18.005Z,1721310138.005 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:42:19.359Z,1721310139.359 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:42:31.423Z,1721310151.423 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:42:38.646Z,1721310158.646 [NavChartDb](INFO): # of records loaded: 10000
2024-07-18T13:42:41.179Z,1721310161.179 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-18T13:42:41.179Z,1721310161.179 [NAL9602] Data Fault, FailCount= 1
2024-07-18T13:42:41.179Z,1721310161.179 [NAL9602](ERROR): Data Fault
2024-07-18T13:42:41.203Z,1721310161.203 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-18T13:42:41.578Z,1721310161.578 [NAL9602](INFO): Powering down
2024-07-18T13:42:42.427Z,1721310162.427 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-18T13:42:42.427Z,1721310162.427 [NAL9602] No Fault, FailCount= 1
2024-07-18T13:42:46.550Z,1721310166.550 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:42:46.587Z,1721310166.587 [NavChartDb](INFO): # of records loaded: 15000
2024-07-18T13:42:51.852Z,1721310171.852 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:42:56.278Z,1721310176.278 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:42:56.427Z,1721310176.427 [NavChartDb](INFO): # of records loaded: 19081
2024-07-18T13:42:56.434Z,1721310176.434 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1ND.000
2024-07-18T13:42:56.435Z,1721310176.435 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:42:59.690Z,1721310179.690 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:43:08.623Z,1721310188.623 [NavChartDb](INFO): # of records loaded: 5000
2024-07-18T13:43:11.878Z,1721310191.878 [NAL9602](INFO): Powering up NAL9602
2024-07-18T13:43:16.401Z,1721310196.401 [NavChartDb](INFO): # of records loaded: 10000
2024-07-18T13:43:22.786Z,1721310202.786 [NAL9602](INFO): NAL9602 initialized
2024-07-18T13:43:23.295Z,1721310203.295 [NavChartDb](INFO): # of records loaded: 15000
2024-07-18T13:43:33.985Z,1721310213.985 [NavChartDb](INFO): # of records loaded: 20000
2024-07-18T13:43:44.260Z,1721310224.260 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2024-07-18T13:43:44.372Z,1721310224.372 [NavChartDb](INFO): # of records loaded: 25000
2024-07-18T13:43:44.688Z,1721310224.688 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 15.422000
2024-07-18T13:43:44.688Z,1721310224.688 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-07-18T13:43:44.688Z,1721310224.688 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-07-18T13:43:45.105Z,1721310225.105 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-07-18T13:43:45.106Z,1721310225.106 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-07-18T13:43:45.106Z,1721310225.106 [IBIT](IMPORTANT): Pressure:10.273356 PSI
2024-07-18T13:43:45.106Z,1721310225.106 [IBIT](IMPORTANT): Humidity:16.646318 %
2024-07-18T13:43:45.495Z,1721310225.495 [IBIT](IMPORTANT): Vehicle Pitch:-2.464601 degrees
2024-07-18T13:43:45.495Z,1721310225.495 [IBIT](IMPORTANT): Vehicle  Roll:-4.004997 degrees
2024-07-18T13:43:45.495Z,1721310225.495 [IBIT](IMPORTANT): Vehicle  Heading:108.649300 degrees
2024-07-18T13:43:45.900Z,1721310225.900 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-07-18T13:43:45.909Z,1721310225.909 [IBIT](IMPORTANT): buoyancyNeutral: 250.000015 cc
2024-07-18T13:43:45.909Z,1721310225.909 [IBIT](IMPORTANT): massDefault: 2.250000 cm
2024-07-18T13:43:45.910Z,1721310225.910 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2024-07-18T13:43:45.910Z,1721310225.910 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2024-07-18T13:43:45.910Z,1721310225.910 [IBIT](IMPORTANT): IBIT FAILED
2024-07-18T13:43:46.287Z,1721310226.287 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-18T13:37:00.1Z
2024-07-18T13:43:46.287Z,1721310226.287 [Default:CheckIn:Read_GPS] Stopped
2024-07-18T13:43:46.287Z,1721310226.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-18T13:43:46.690Z,1721310226.690 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-18T13:43:57.100Z,1721310237.100 [NavChartDb](INFO): # of records loaded: 30000
2024-07-18T13:44:03.236Z,1721310243.236 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240718T133147/Courier0004.lzma
2024-07-18T13:44:03.762Z,1721310243.762 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-07-18T13:44:03.762Z,1721310243.762 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-18T13:44:03.827Z,1721310243.827 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-18T13:44:04.167Z,1721310244.167 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-18T13:44:04.167Z,1721310244.167 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-07-18T13:44:04.239Z,1721310244.239 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Courier0004.lzma.bak
2024-07-18T13:44:04.239Z,1721310244.239 [DataOverHttps](INFO): SBD MOMSN=21363027
2024-07-18T13:44:10.683Z,1721310250.683 [NavChartDb](INFO): # of records loaded: 35000
2024-07-18T13:44:22.662Z,1721310262.662 [NavChartDb](INFO): # of records loaded: 40000
2024-07-18T13:44:29.680Z,1721310269.680 [NavChartDb](INFO): # of records loaded: 45000
2024-07-18T13:44:31.752Z,1721310271.752 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:44:38.822Z,1721310278.822 [DataOverHttps](INFO): Sending 1240 bytes from file Logs/20240718T133147/Express0001.lzma
2024-07-18T13:44:39.555Z,1721310279.555 [NavChartDb](INFO): # of records loaded: 50000
2024-07-18T13:44:39.823Z,1721310279.823 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Express0001.lzma.bak
2024-07-18T13:44:39.823Z,1721310279.823 [DataOverHttps](INFO): SBD MOMSN=21363029
2024-07-18T13:44:53.944Z,1721310293.944 [NavChartDb](INFO): # of records loaded: 55000
2024-07-18T13:45:04.860Z,1721310304.860 [NavChartDb](INFO): # of records loaded: 60000
2024-07-18T13:45:07.511Z,1721310307.511 [NavChartDb](CRITICAL): SIGSEGV: address not mapped to object
2024-07-18T13:45:07.512Z,1721310307.512 [NavChartDb] Software Fault, FailCount= 1
2024-07-18T13:45:07.512Z,1721310307.512 [NavChartDb](ERROR): Software Fault
2024-07-18T13:45:07.647Z,1721310307.647 [CommandExec](FAULT): Scheduling is paused
2024-07-18T13:45:07.647Z,1721310307.647 [CBIT](INFO): Critical error at 20240718T134507
2024-07-18T13:45:07.648Z,1721310307.648 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-18T13:45:07.655Z,1721310307.655 [CBIT](ERROR): Software Fault in component: NavChartDb
2024-07-18T13:45:07.655Z,1721310307.655 [CBIT](CRITICAL): Software Fault in component: NavChartDb
2024-07-18T13:45:07.921Z,1721310307.921 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-18T13:45:07.921Z,1721310307.921 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1037
2024-07-18T13:45:08.066Z,1721310308.066 [CBIT](INFO): Critical error at 20240718T134507
2024-07-18T13:45:11.155Z,1721310311.155 [DataOverHttps](INFO): Sending 1500 bytes from file Logs/20240718T133147/Express0005.lzma
2024-07-18T13:45:12.154Z,1721310312.154 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Express0005.lzma.bak
2024-07-18T13:45:12.155Z,1721310312.155 [DataOverHttps](INFO): SBD MOMSN=21363061
2024-07-18T13:45:17.288Z,1721310317.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134158.00,A,4538.85860,N,08428.37107,W,0.039,147.20,180724,,,A*79
2024-07-18T13:45:17.291Z,1721310317.291 [NAL9602](INFO): GPS fix at 20240718T134158: (45.647643, -84.472851)
2024-07-18T13:45:18.929Z,1721310318.929 [Default:CheckIn:Read_Iridium] Stopped
2024-07-18T13:45:18.930Z,1721310318.930 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-18T13:45:18.930Z,1721310318.930 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-18T13:45:50.014Z,1721310350.014 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-18T13:50:19.532Z,1721310619.532 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-18T13:50:19.532Z,1721310619.532 [Default:CheckIn:C.Wait] Stopped
2024-07-18T13:50:19.532Z,1721310619.532 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-18T13:50:19.533Z,1721310619.533 [Default:CheckIn:D] Running Loop=1
2024-07-18T13:50:19.946Z,1721310619.946 [Default:CheckIn:D] Stopped
2024-07-18T13:50:19.946Z,1721310619.946 [Default:CheckIn:E] Running Loop=1
2024-07-18T13:50:20.329Z,1721310620.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  13.560796 min 
2024-07-18T13:50:20.329Z,1721310620.329 [Default:CheckIn:E] Stopped
2024-07-18T13:50:20.329Z,1721310620.329 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-18T13:50:20.329Z,1721310620.329 [Default:CheckIn] Stopped
2024-07-18T13:50:20.329Z,1721310620.329 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-18T13:50:20.330Z,1721310620.330 [Default:CheckIn](INFO): Running loop #2
2024-07-18T13:50:20.330Z,1721310620.330 [Default:CheckIn] Running Loop=2
2024-07-18T13:50:20.330Z,1721310620.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-18T13:50:20.330Z,1721310620.330 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-18T13:50:22.332Z,1721310622.332 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,134704.00,A,4538.85003,N,08428.37783,W,1.263,334.29,180724,,,A*72
2024-07-18T13:50:22.334Z,1721310622.334 [NAL9602](INFO): GPS fix at 20240718T134704: (45.647500, -84.472964)
2024-07-18T13:50:22.370Z,1721310622.370 [Default:CheckIn:Read_GPS] Stopped
2024-07-18T13:50:22.370Z,1721310622.370 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-18T13:50:33.677Z,1721310633.677 [DataOverHttps](INFO): Sending 331 bytes from file Logs/20240718T133147/Courier0007.lzma
2024-07-18T13:50:34.679Z,1721310634.679 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Courier0007.lzma.bak
2024-07-18T13:50:34.679Z,1721310634.679 [DataOverHttps](INFO): SBD MOMSN=21363200
2024-07-18T13:50:56.965Z,1721310656.965 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20240718T133147/Express0008.lzma
2024-07-18T13:50:57.967Z,1721310657.967 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Express0008.lzma.bak
2024-07-18T13:50:57.967Z,1721310657.967 [DataOverHttps](INFO): SBD MOMSN=21363205
2024-07-18T13:51:04.364Z,1721310664.364 [Default:CheckIn:Read_Iridium] Stopped
2024-07-18T13:51:04.385Z,1721310664.385 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-18T13:51:04.385Z,1721310664.385 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-18T13:52:09.390Z,1721310729.390 [NAL9602](INFO): SBD MO Status=2, MOMSN=5200, MT Status=2, MTMSN=0
2024-07-18T13:52:09.391Z,1721310729.391 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-18T13:55:01.206Z,1721310901.206 [CommandExec](IMPORTANT): got command failComponent
2024-07-18T13:55:01.207Z,1721310901.207 [CommandExec](IMPORTANT): Failed components:
2024-07-18T13:55:01.207Z,1721310901.207 [CommandExec](IMPORTANT): NavChartDb: Software Fault
2024-07-18T13:55:15.610Z,1721310915.610 [CommandExec](IMPORTANT): got command failComponent
2024-07-18T13:55:15.610Z,1721310915.610 [CommandExec](IMPORTANT): Failed components:
2024-07-18T13:55:15.610Z,1721310915.610 [CommandExec](IMPORTANT): NavChartDb: Software Fault
2024-07-18T13:55:20.621Z,1721310920.621 [CommandExec](IMPORTANT): got command failComponent none NavChartDb
2024-07-18T13:55:20.621Z,1721310920.621 [NavChartDb] No Fault, FailCount= 1
2024-07-18T13:55:20.621Z,1721310920.621 [CommandExec](IMPORTANT): NavChartDb failureMode is No Fault
2024-07-18T13:55:20.802Z,1721310920.802 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:55:20.802Z,1721310920.802 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2024-07-18T13:55:20.907Z,1721310920.907 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:55:20.908Z,1721310920.908 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000
2024-07-18T13:55:20.908Z,1721310920.908 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:55:20.909Z,1721310920.909 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000
2024-07-18T13:55:20.910Z,1721310920.910 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:55:20.911Z,1721310920.911 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000
2024-07-18T13:55:20.911Z,1721310920.911 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:55:20.933Z,1721310920.933 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000
2024-07-18T13:55:20.933Z,1721310920.933 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:55:20.935Z,1721310920.935 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000
2024-07-18T13:55:20.935Z,1721310920.935 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:55:20.936Z,1721310920.936 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000
2024-07-18T13:55:20.936Z,1721310920.936 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:55:20.938Z,1721310920.938 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MF.000
2024-07-18T13:55:20.938Z,1721310920.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:55:20.940Z,1721310920.940 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5MI61M.000
2024-07-18T13:55:20.940Z,1721310920.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:55:20.970Z,1721310920.970 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NG.000
2024-07-18T13:55:20.970Z,1721310920.970 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:55:20.971Z,1721310920.971 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MG.000
2024-07-18T13:55:20.972Z,1721310920.972 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:55:20.982Z,1721310920.982 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NH.000
2024-07-18T13:55:21.059Z,1721310921.059 [NavChartDb](CRITICAL): SIGSEGV: address not mapped to object
2024-07-18T13:55:21.059Z,1721310921.059 [NavChartDb] Software Fault, FailCount= 2
2024-07-18T13:55:21.059Z,1721310921.059 [NavChartDb](ERROR): Software Fault
2024-07-18T13:55:21.332Z,1721310921.332 [CBIT](INFO): Critical error at 20240718T135521
2024-07-18T13:55:21.334Z,1721310921.334 [CBIT](ERROR): Software Fault in component: NavChartDb
2024-07-18T13:55:21.336Z,1721310921.336 [CBIT](CRITICAL): Software Fault in component: NavChartDb
2024-07-18T13:55:21.461Z,1721310921.461 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-18T13:55:21.461Z,1721310921.461 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1096
2024-07-18T13:55:21.744Z,1721310921.744 [CBIT](INFO): Critical error at 20240718T135521
2024-07-18T13:55:24.525Z,1721310924.525 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-18T13:55:40.610Z,1721310940.610 [CommandExec](IMPORTANT): got command failComponent
2024-07-18T13:55:40.610Z,1721310940.610 [CommandExec](IMPORTANT): Failed components:
2024-07-18T13:55:40.611Z,1721310940.611 [CommandExec](IMPORTANT): NavChartDb: Software Fault
2024-07-18T13:55:46.236Z,1721310946.236 [CommandExec](IMPORTANT): got command failComponent none NavChartDb
2024-07-18T13:55:46.236Z,1721310946.236 [NavChartDb] No Fault, FailCount= 2
2024-07-18T13:55:46.236Z,1721310946.236 [CommandExec](IMPORTANT): NavChartDb failureMode is No Fault
2024-07-18T13:55:46.527Z,1721310946.527 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2024-07-18T13:55:46.630Z,1721310946.630 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-18T13:55:46.632Z,1721310946.632 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000
2024-07-18T13:55:46.632Z,1721310946.632 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-18T13:55:46.633Z,1721310946.633 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000
2024-07-18T13:55:46.633Z,1721310946.633 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-18T13:55:46.635Z,1721310946.635 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000
2024-07-18T13:55:46.635Z,1721310946.635 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-18T13:55:46.637Z,1721310946.637 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000
2024-07-18T13:55:46.637Z,1721310946.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-18T13:55:46.638Z,1721310946.638 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000
2024-07-18T13:55:46.638Z,1721310946.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-18T13:55:46.640Z,1721310946.640 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000
2024-07-18T13:55:46.640Z,1721310946.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-18T13:55:46.642Z,1721310946.642 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MF.000
2024-07-18T13:55:46.642Z,1721310946.642 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-18T13:55:46.643Z,1721310946.643 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5MI61M.000
2024-07-18T13:55:46.644Z,1721310946.644 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NG.000
2024-07-18T13:55:46.646Z,1721310946.646 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NG.000
2024-07-18T13:55:46.646Z,1721310946.646 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MG.000
2024-07-18T13:55:46.648Z,1721310946.648 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MG.000
2024-07-18T13:55:46.648Z,1721310946.648 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NH.000
2024-07-18T13:55:46.650Z,1721310946.650 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NH.000
2024-07-18T13:55:46.789Z,1721310946.789 [NavChartDb](CRITICAL): SIGSEGV: address not mapped to object
2024-07-18T13:55:46.789Z,1721310946.789 [NavChartDb] Software Fault, FailCount= 3
2024-07-18T13:55:46.789Z,1721310946.789 [NavChartDb](ERROR): Software Fault
2024-07-18T13:55:46.819Z,1721310946.819 [CBIT](INFO): Critical error at 20240718T135546
2024-07-18T13:55:46.821Z,1721310946.821 [CBIT](ERROR): Software Fault in component: NavChartDb
2024-07-18T13:55:46.821Z,1721310946.821 [CBIT](CRITICAL): Software Fault in component: NavChartDb
2024-07-18T13:55:47.174Z,1721310947.174 [CBIT](INFO): Critical error at 20240718T135546
2024-07-18T13:55:47.193Z,1721310947.193 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-18T13:55:47.193Z,1721310947.193 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1102
2024-07-18T13:55:55.230Z,1721310955.230 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-18T13:56:04.965Z,1721310964.965 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-18T13:56:04.965Z,1721310964.965 [Default:CheckIn:C.Wait] Stopped
2024-07-18T13:56:04.965Z,1721310964.965 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-18T13:56:04.966Z,1721310964.966 [Default:CheckIn:D] Running Loop=1
2024-07-18T13:56:05.354Z,1721310965.354 [Default:CheckIn:D] Stopped
2024-07-18T13:56:05.354Z,1721310965.354 [Default:CheckIn:E] Running Loop=1
2024-07-18T13:56:05.819Z,1721310965.819 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  19.317594 min 
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn:E] Stopped
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn] Stopped
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn](INFO): Running loop #3
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn] Running Loop=3
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-18T13:56:05.820Z,1721310965.820 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-18T13:56:07.755Z,1721310967.755 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,135249.00,A,4538.87550,N,08428.40302,W,0.816,344.14,180724,,,A*73
2024-07-18T13:56:07.758Z,1721310967.758 [NAL9602](INFO): GPS fix at 20240718T135249: (45.647925, -84.473384)
2024-07-18T13:56:07.844Z,1721310967.844 [Default:CheckIn:Read_GPS] Stopped
2024-07-18T13:56:07.844Z,1721310967.844 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-18T13:56:19.836Z,1721310979.836 [DataOverHttps](INFO): Sending 172 bytes from file Logs/20240718T133147/Courier0010.lzma
2024-07-18T13:56:20.839Z,1721310980.839 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Courier0010.lzma.bak
2024-07-18T13:56:20.839Z,1721310980.839 [DataOverHttps](INFO): SBD MOMSN=21363278
2024-07-18T13:56:38.530Z,1721310998.530 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-18T13:56:38.605Z,1721310998.605 [NAL9602](ERROR): received: 
+CSQ:0

OK00, 2, 0, 0, 0

OK
2024-07-18T13:56:41.845Z,1721311001.845 [DataOverHttps](INFO): Sending 550 bytes from file Logs/20240718T133147/Express0011.lzma
2024-07-18T13:56:42.846Z,1721311002.846 [DataOverHttps](INFO): Moved sent file to Logs/20240718T133147/Express0011.lzma.bak
2024-07-18T13:56:42.847Z,1721311002.847 [DataOverHttps](INFO): SBD MOMSN=21363285
2024-07-18T13:56:48.413Z,1721311008.413 [CommandExec](IMPORTANT): got command show variable charts
2024-07-18T13:56:48.514Z,1721311008.514 [CommandExec](IMPORTANT): NavChartDb.charts (none)
2024-07-18T13:56:49.463Z,1721311009.463 [Default:CheckIn:Read_Iridium] Stopped
2024-07-18T13:56:49.464Z,1721311009.464 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-18T13:56:49.464Z,1721311009.464 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-18T13:56:58.366Z,1721311018.366 [CommandExec](IMPORTANT): got command get NavChartDb.charts
2024-07-18T13:56:58.366Z,1721311018.366 [CommandExec](IMPORTANT): NavChartDb.charts US2MI60M,US4MI1ND,US4MI1OD,US4MI51M,US4MI1OF,US4MI1NF,US4MI1MF,US5MI61M,US4MI1NG,US4MI1MG,US4MI1NH
2024-07-18T13:58:22.758Z,1721311102.758 [NAL9602](INFO): SBD MO Status=2, MOMSN=5200, MT Status=2, MTMSN=0
2024-07-18T13:58:22.758Z,1721311102.758 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-18T13:59:32.106Z,1721311172.106 [DataOverHttps](IMPORTANT): SBD MTMSN=20240718T135613
2024-07-18T13:59:43.237Z,1721311183.237 [DataOverHttps](INFO): Received command: restart app
2024-07-18T13:59:43.319Z,1721311183.319 [CommandExec](IMPORTANT): got command restart application
2024-07-18T13:59:44.321Z,1721311184.321 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:44.321Z,1721311184.321 [CommandExec](INFO): Uninitializing the command executive.
2024-07-18T13:59:44.321Z,1721311184.321 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-18T13:59:44.321Z,1721311184.321 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.429Z,1721311184.429 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2024-07-18T13:59:44.429Z,1721311184.429 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-18T13:59:44.429Z,1721311184.429 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.430Z,1721311184.430 [NavChartDb](INFO): Join timeout helper Thread ID is 1126
2024-07-18T13:59:44.469Z,1721311184.469 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:44.469Z,1721311184.469 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.489Z,1721311184.489 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-18T13:59:44.489Z,1721311184.489 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.489Z,1721311184.489 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1127
2024-07-18T13:59:44.513Z,1721311184.513 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:44.513Z,1721311184.513 [WetLabsBB2FL](INFO): Powering down
2024-07-18T13:59:44.514Z,1721311184.514 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.515Z,1721311184.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-18T13:59:44.515Z,1721311184.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:44.515Z,1721311184.515 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1128
2024-07-18T13:59:45.297Z,1721311185.297 [CTD_Seabird](INFO): Powering down
2024-07-18T13:59:45.309Z,1721311185.309 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:45.309Z,1721311185.309 [CTD_Seabird](INFO): Powering down
2024-07-18T13:59:45.321Z,1721311185.321 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:45.337Z,1721311185.337 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-18T13:59:45.337Z,1721311185.337 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:45.337Z,1721311185.337 [Radio_Surface](INFO): Join timeout helper Thread ID is 1129
2024-07-18T13:59:45.677Z,1721311185.677 [Radio_Surface](INFO): Powering down
2024-07-18T13:59:45.678Z,1721311185.678 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:45.678Z,1721311185.678 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:45.681Z,1721311185.681 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-18T13:59:45.681Z,1721311185.681 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:45.681Z,1721311185.681 [Onboard](INFO): Join timeout helper Thread ID is 1130
2024-07-18T13:59:46.245Z,1721311186.245 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-07-18T13:59:46.621Z,1721311186.621 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:46.621Z,1721311186.621 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:46.626Z,1721311186.626 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-18T13:59:46.626Z,1721311186.626 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:46.626Z,1721311186.626 [DataOverHttps](INFO): Join timeout helper Thread ID is 1131
2024-07-18T13:59:47.249Z,1721311187.249 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-18T13:59:47.249Z,1721311187.249 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:47.257Z,1721311187.257 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-18T13:59:47.257Z,1721311187.257 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-18T13:59:47.258Z,1721311187.258 [BackseatComponent](INFO): Join timeout helper Thread ID is 1132