2024-07-19T01:59:59.155Z,1721354399.155 [Supervisor](DEBUG): Initializing supervisor.
2024-07-19T01:59:59.159Z,1721354399.159 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-19T01:59:59.160Z,1721354399.160 [SyncHandler](INFO): Protected caller Thread ID is 838
2024-07-19T01:59:59.160Z,1721354399.160 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-19T01:59:59.161Z,1721354399.161 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-19T01:59:59.162Z,1721354399.162 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839
2024-07-19T01:59:59.166Z,1721354399.166 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-19T01:59:59.184Z,1721354399.184 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-19T01:59:59.185Z,1721354399.185 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-19T01:59:59.185Z,1721354399.185 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840
2024-07-19T01:59:59.190Z,1721354399.190 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-19T01:59:59.191Z,1721354399.191 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-19T01:59:59.191Z,1721354399.191 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841
2024-07-19T01:59:59.193Z,1721354399.193 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-19T01:59:59.194Z,1721354399.194 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-19T01:59:59.195Z,1721354399.195 [logger ThreadHandler](INFO): Protected caller Thread ID is 842
2024-07-19T01:59:59.198Z,1721354399.198 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-19T01:59:59.199Z,1721354399.199 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-19T01:59:59.203Z,1721354399.203 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-19T01:59:59.741Z,1721354399.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-19T01:59:59.743Z,1721354399.743 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-19T01:59:59.978Z,1721354399.978 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-19T01:59:59.980Z,1721354399.980 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-19T02:00:00.074Z,1721354400.074 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-19T02:00:00.333Z,1721354400.333 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-19T02:00:00.335Z,1721354400.335 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-19T02:00:00.427Z,1721354400.427 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-19T02:00:00.535Z,1721354400.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-19T02:00:00.537Z,1721354400.537 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-19T02:00:00.909Z,1721354400.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-19T02:00:00.910Z,1721354400.910 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-19T02:00:01.058Z,1721354401.058 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-19T02:00:01.060Z,1721354401.060 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-19T02:00:01.633Z,1721354401.633 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-19T02:00:01.634Z,1721354401.634 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-19T02:00:01.844Z,1721354401.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-19T02:00:01.846Z,1721354401.846 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-19T02:00:02.286Z,1721354402.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-19T02:00:02.287Z,1721354402.287 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-19T02:00:02.605Z,1721354402.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-19T02:00:02.605Z,1721354402.605 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-19T02:00:02.773Z,1721354402.773 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-19T02:00:03.178Z,1721354403.178 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-19T02:00:04.212Z,1721354404.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-19T02:00:04.214Z,1721354404.214 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-19T02:00:04.597Z,1721354404.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-19T02:00:04.599Z,1721354404.599 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-19T02:00:04.851Z,1721354404.851 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-19T02:00:04.853Z,1721354404.853 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-19T02:00:04.855Z,1721354404.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-19T02:00:04.988Z,1721354404.988 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-19T02:00:05.085Z,1721354405.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-19T02:00:05.238Z,1721354405.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-19T02:00:05.336Z,1721354405.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-19T02:00:05.614Z,1721354405.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-19T02:00:05.615Z,1721354405.615 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-19T02:00:05.735Z,1721354405.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-19T02:00:05.856Z,1721354405.856 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-19T02:00:05.979Z,1721354405.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-19T02:00:06.146Z,1721354406.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-19T02:00:06.258Z,1721354406.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-19T02:00:06.359Z,1721354406.359 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-19T02:00:06.371Z,1721354406.371 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-19T02:00:06.387Z,1721354406.387 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-19T02:00:06.388Z,1721354406.388 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-19T02:00:06.534Z,1721354406.534 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-19T02:00:06.535Z,1721354406.535 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-19T02:00:06.605Z,1721354406.605 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-19T02:00:06.667Z,1721354406.667 [VerticalControl] Loaded
2024-07-19T02:00:06.667Z,1721354406.667 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-19T02:00:06.670Z,1721354406.670 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-19T02:00:06.718Z,1721354406.718 [HorizontalControl] Loaded
2024-07-19T02:00:06.719Z,1721354406.719 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-19T02:00:06.721Z,1721354406.721 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-19T02:00:06.724Z,1721354406.724 [SpeedControl] Loaded
2024-07-19T02:00:06.724Z,1721354406.724 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-19T02:00:06.727Z,1721354406.727 [LoopControl](DEBUG): Construct LoopControl.
2024-07-19T02:00:06.727Z,1721354406.727 [LoopControl] Loaded
2024-07-19T02:00:06.728Z,1721354406.728 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-19T02:00:06.728Z,1721354406.728 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-19T02:00:06.730Z,1721354406.730 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-19T02:00:06.919Z,1721354406.919 [BuoyancyServo] Loaded
2024-07-19T02:00:06.920Z,1721354406.920 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-19T02:00:06.942Z,1721354406.942 [ElevatorServo] Loaded
2024-07-19T02:00:06.943Z,1721354406.943 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-19T02:00:06.965Z,1721354406.965 [MassServo] Loaded
2024-07-19T02:00:06.965Z,1721354406.965 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-19T02:00:06.985Z,1721354406.985 [RudderServo] Loaded
2024-07-19T02:00:06.986Z,1721354406.986 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-19T02:00:07.002Z,1721354407.002 [ThrusterHE] Loaded
2024-07-19T02:00:07.003Z,1721354407.003 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-19T02:00:07.003Z,1721354407.003 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-19T02:00:07.004Z,1721354407.004 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-19T02:00:07.074Z,1721354407.074 [DepthRateCalculator] Loaded
2024-07-19T02:00:07.074Z,1721354407.074 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-19T02:00:07.079Z,1721354407.079 [PitchRateCalculator] Loaded
2024-07-19T02:00:07.079Z,1721354407.079 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-19T02:00:07.089Z,1721354407.089 [SpeedCalculator] Loaded
2024-07-19T02:00:07.089Z,1721354407.089 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-19T02:00:07.094Z,1721354407.094 [YawRateCalculator] Loaded
2024-07-19T02:00:07.094Z,1721354407.094 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-19T02:00:07.113Z,1721354407.113 [ElevatorOffsetCalculator] Loaded
2024-07-19T02:00:07.113Z,1721354407.113 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-19T02:00:07.113Z,1721354407.113 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-19T02:00:07.114Z,1721354407.114 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-19T02:00:07.251Z,1721354407.251 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-19T02:00:07.252Z,1721354407.252 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-19T02:00:07.266Z,1721354407.266 [NavChart] Loaded
2024-07-19T02:00:07.266Z,1721354407.266 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-19T02:00:07.272Z,1721354407.272 [UniversalFixResidualReporter] Loaded
2024-07-19T02:00:07.272Z,1721354407.272 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-19T02:00:07.272Z,1721354407.272 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-19T02:00:07.273Z,1721354407.273 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-19T02:00:08.303Z,1721354408.303 [AHRS_M2] Loaded
2024-07-19T02:00:08.304Z,1721354408.304 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-19T02:00:08.558Z,1721354408.558 [BackseatComponent] Loaded
2024-07-19T02:00:08.558Z,1721354408.558 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-19T02:00:08.559Z,1721354408.559 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-19T02:00:08.559Z,1721354408.559 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924
2024-07-19T02:00:08.562Z,1721354408.562 [LcmUniversalReporter] Loaded
2024-07-19T02:00:08.562Z,1721354408.562 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-19T02:00:08.569Z,1721354408.569 [BioacousticsDataBridge] Loaded
2024-07-19T02:00:08.569Z,1721354408.569 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-19T02:00:09.403Z,1721354409.403 [BPC1] Loaded
2024-07-19T02:00:09.403Z,1721354409.403 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-19T02:00:09.542Z,1721354409.542 [DataOverHttps] Loaded
2024-07-19T02:00:09.542Z,1721354409.542 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-19T02:00:09.543Z,1721354409.543 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-19T02:00:09.544Z,1721354409.544 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2024-07-19T02:00:09.567Z,1721354409.567 [Depth_Keller] Loaded
2024-07-19T02:00:09.567Z,1721354409.567 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-19T02:00:09.572Z,1721354409.572 [DropWeight] Loaded
2024-07-19T02:00:09.573Z,1721354409.573 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-19T02:00:09.637Z,1721354409.637 [NAL9602] Loaded
2024-07-19T02:00:09.637Z,1721354409.637 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-19T02:00:09.667Z,1721354409.667 [Onboard] Loaded
2024-07-19T02:00:09.667Z,1721354409.667 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-19T02:00:09.668Z,1721354409.668 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-19T02:00:09.669Z,1721354409.669 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2024-07-19T02:00:09.683Z,1721354409.683 [Power24vConverter] Loaded
2024-07-19T02:00:09.684Z,1721354409.684 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-19T02:00:09.699Z,1721354409.699 [Radio_Surface] Loaded
2024-07-19T02:00:09.699Z,1721354409.699 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-19T02:00:09.701Z,1721354409.701 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-19T02:00:09.701Z,1721354409.701 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2024-07-19T02:00:09.716Z,1721354409.716 [Sonardyne_Nano] Loaded
2024-07-19T02:00:09.717Z,1721354409.717 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-19T02:00:09.717Z,1721354409.717 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-19T02:00:09.718Z,1721354409.718 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-19T02:00:09.800Z,1721354409.800 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-19T02:00:09.801Z,1721354409.801 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-19T02:00:10.019Z,1721354410.019 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-19T02:00:10.020Z,1721354410.020 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-19T02:00:10.065Z,1721354410.065 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-19T02:00:10.066Z,1721354410.066 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-19T02:00:10.290Z,1721354410.290 [CTD_Seabird] Loaded
2024-07-19T02:00:10.290Z,1721354410.290 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-19T02:00:10.291Z,1721354410.291 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-19T02:00:10.291Z,1721354410.291 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2024-07-19T02:00:10.314Z,1721354410.314 [PAR_Licor] Loaded
2024-07-19T02:00:10.314Z,1721354410.314 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-19T02:00:10.361Z,1721354410.361 [WetLabsBB2FL] Loaded
2024-07-19T02:00:10.361Z,1721354410.361 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-19T02:00:10.362Z,1721354410.362 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-19T02:00:10.363Z,1721354410.363 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2024-07-19T02:00:10.363Z,1721354410.363 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-19T02:00:10.364Z,1721354410.364 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-19T02:00:10.766Z,1721354410.766 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-19T02:00:10.767Z,1721354410.767 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-19T02:00:10.943Z,1721354410.943 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-19T02:00:10.953Z,1721354410.953 [SBIT] Loaded
2024-07-19T02:00:10.953Z,1721354410.953 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-19T02:00:10.956Z,1721354410.956 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-19T02:00:10.969Z,1721354410.969 [IBIT] Loaded
2024-07-19T02:00:10.969Z,1721354410.969 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-19T02:00:10.975Z,1721354410.975 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-19T02:00:11.081Z,1721354411.081 [CBIT] Loaded
2024-07-19T02:00:11.081Z,1721354411.081 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-19T02:00:11.082Z,1721354411.082 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-19T02:00:11.088Z,1721354411.088 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-19T02:00:11.091Z,1721354411.091 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-19T02:00:11.103Z,1721354411.103 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-19T02:00:11.104Z,1721354411.104 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-19T02:00:11.105Z,1721354411.105 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2024-07-19T02:00:11.109Z,1721354411.109 [Supervisor](INFO): Main Thread ID is 831
2024-07-19T02:00:11.109Z,1721354411.109 [Supervisor](DEBUG): Running supervisor.
2024-07-19T02:00:11.110Z,1721354411.110 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2024-07-19T02:00:11.110Z,1721354411.110 [CommandExec](INFO): Initializing the command executive.
2024-07-19T02:00:11.112Z,1721354411.112 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2024-07-19T02:00:11.114Z,1721354411.114 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2024-07-19T02:00:11.114Z,1721354411.114 [controlThread](DEBUG): Initializing ControlThread
2024-07-19T02:00:11.115Z,1721354411.115 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-19T02:00:11.117Z,1721354411.117 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-19T02:00:11.117Z,1721354411.117 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-19T02:00:11.118Z,1721354411.118 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-19T02:00:11.119Z,1721354411.119 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-19T02:00:11.119Z,1721354411.119 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-19T02:00:11.120Z,1721354411.120 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-19T02:00:11.120Z,1721354411.120 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-19T02:00:11.120Z,1721354411.120 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-19T02:00:11.121Z,1721354411.121 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-19T02:00:11.122Z,1721354411.122 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-19T02:00:11.128Z,1721354411.128 [SBIT](INFO): Initialize SBIT Component.
2024-07-19T02:00:11.129Z,1721354411.129 [SBIT](IMPORTANT): git: 2024-07-18
2024-07-19T02:00:11.129Z,1721354411.129 [SBIT](INFO): git hash: e777c895b2d7d743d45b75a88845e2c8d3f60819
2024-07-19T02:00:11.129Z,1721354411.129 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-19T02:00:11.130Z,1721354411.130 [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-19T02:00:11.131Z,1721354411.131 [SBIT](INFO): Beginning SBIT in 110.000000 seconds.
2024-07-19T02:00:11.132Z,1721354411.132 [IBIT](INFO): Initialize IBIT Component.
2024-07-19T02:00:11.133Z,1721354411.133 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-19T02:00:11.134Z,1721354411.134 [logger ThreadHandler](INFO): Handler Thread ID is 934
2024-07-19T02:00:11.144Z,1721354411.144 [CBIT](DEBUG): Initialized mux pins.
2024-07-19T02:00:11.144Z,1721354411.144 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-19T02:00:11.152Z,1721354411.152 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2024-07-19T02:00:11.164Z,1721354411.164 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 936
2024-07-19T02:00:11.166Z,1721354411.166 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-19T02:00:11.168Z,1721354411.168 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-19T02:00:11.168Z,1721354411.168 [CBIT](DEBUG): Initializing heartbeat.
2024-07-19T02:00:11.176Z,1721354411.176 [Onboard ThreadHandler](INFO): Handler Thread ID is 937
2024-07-19T02:00:11.194Z,1721354411.194 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 938
2024-07-19T02:00:11.213Z,1721354411.213 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 939
2024-07-19T02:00:11.213Z,1721354411.213 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-19T02:00:11.217Z,1721354411.217 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 941
2024-07-19T02:00:11.220Z,1721354411.220 [WetLabsBB2FL](INFO): Powering up
2024-07-19T02:00:11.221Z,1721354411.221 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 943
2024-07-19T02:00:11.229Z,1721354411.229 [NavChartDb](FAULT): Change detected in ENC collection. Wiping NavChart Directory
2024-07-19T02:00:11.240Z,1721354411.240 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-19T02:00:11.240Z,1721354411.240 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-19T02:00:11.252Z,1721354411.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:00:11.254Z,1721354411.254 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2MI60M.000
2024-07-19T02:00:11.254Z,1721354411.254 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:00:11.255Z,1721354411.255 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1ND.000
2024-07-19T02:00:11.255Z,1721354411.255 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:00:11.257Z,1721354411.257 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OD.000
2024-07-19T02:00:11.257Z,1721354411.257 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:00:11.258Z,1721354411.258 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI51M.000
2024-07-19T02:00:11.258Z,1721354411.258 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:00:11.260Z,1721354411.260 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1OF.000
2024-07-19T02:00:11.260Z,1721354411.260 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:00:11.261Z,1721354411.261 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1NF.000
2024-07-19T02:00:11.262Z,1721354411.262 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:00:11.263Z,1721354411.263 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US4MI1MF.000
2024-07-19T02:00:11.263Z,1721354411.263 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:00:11.277Z,1721354411.277 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5MI61M.000
2024-07-19T02:00:11.280Z,1721354411.280 [CBIT](DEBUG): Backplane powered.
2024-07-19T02:00:11.281Z,1721354411.281 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-19T02:00:11.281Z,1721354411.281 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-19T02:00:11.282Z,1721354411.282 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-19T02:00:11.288Z,1721354411.288 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-19T02:00:11.336Z,1721354411.336 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-19T02:00:11.349Z,1721354411.349 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-19T02:00:11.352Z,1721354411.352 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:00:11.392Z,1721354411.392 [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-19T02:00:11.395Z,1721354411.395 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-19T02:00:11.395Z,1721354411.395 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-19T02:00:11.418Z,1721354411.418 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-19T02:00:11.600Z,1721354411.600 [Radio_Surface](INFO): Powering up
2024-07-19T02:00:11.708Z,1721354411.708 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-07-19T02:00:11.722Z,1721354411.722 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-19T02:00:11.723Z,1721354411.723 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-19T02:00:11.725Z,1721354411.725 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-19T02:00:11.749Z,1721354411.749 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-19T02:00:11.752Z,1721354411.752 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-19T02:00:11.766Z,1721354411.766 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-19T02:00:11.795Z,1721354411.795 [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-19T02:00:11.799Z,1721354411.799 [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-19T02:00:11.818Z,1721354411.818 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-19T02:00:11.986Z,1721354411.986 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1926.023804 decibar, 0.000000 m
2024-07-19T02:00:11.987Z,1721354411.987 [Power24vConverter](INFO): Powering up.
2024-07-19T02:00:11.992Z,1721354411.992 [Sonardyne_Nano](INFO): Initializing.
2024-07-19T02:00:12.028Z,1721354412.028 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-07-19T02:00:12.042Z,1721354412.042 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-19T02:00:12.048Z,1721354412.048 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-19T02:00:12.049Z,1721354412.049 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-19T02:00:12.056Z,1721354412.056 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-19T02:00:12.057Z,1721354412.057 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-19T02:00:12.064Z,1721354412.064 [MassServo](DEBUG): Initializing MassServo.
2024-07-19T02:00:12.065Z,1721354412.065 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-19T02:00:12.072Z,1721354412.072 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-19T02:00:12.073Z,1721354412.073 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-19T02:00:12.104Z,1721354412.104 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-19T02:00:13.324Z,1721354413.324 [WetLabsBB2FL](INFO): Powering down
2024-07-19T02:00:14.235Z,1721354414.235 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-19T02:00:15.478Z,1721354415.478 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-19T02:00:17.749Z,1721354417.749 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-19T02:00:21.476Z,1721354421.476 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:00:33.889Z,1721354433.889 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:00:39.702Z,1721354439.702 [NAL9602](INFO): Powering up NAL9602
2024-07-19T02:00:44.522Z,1721354444.522 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T02:00:50.606Z,1721354450.606 [NAL9602](INFO): NAL9602 initialized
2024-07-19T02:00:55.074Z,1721354455.074 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T02:01:04.377Z,1721354464.377 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T02:01:13.612Z,1721354473.612 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T02:01:18.884Z,1721354478.884 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:01:18.918Z,1721354478.918 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:01:21.074Z,1721354481.074 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T02:01:27.817Z,1721354487.817 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T02:01:33.425Z,1721354493.425 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:01:36.189Z,1721354496.189 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T02:01:43.218Z,1721354503.218 [NavChartDb](INFO): # of records loaded: 45000
2024-07-19T02:01:47.968Z,1721354507.968 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:01:50.450Z,1721354510.450 [NavChartDb](INFO): # of records loaded: 50000
2024-07-19T02:01:57.949Z,1721354517.949 [NavChartDb](INFO): # of records loaded: 55000
2024-07-19T02:01:59.790Z,1721354519.790 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:02:01.801Z,1721354521.801 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-19T02:02:01.809Z,1721354521.809 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-19T02:02:02.109Z,1721354522.109 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:02:05.473Z,1721354525.473 [NavChartDb](INFO): # of records loaded: 60000
2024-07-19T02:02:12.785Z,1721354532.785 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002404
CHAN A1 (24V): -0.007719
CHAN A2 (12V): -0.004870
CHAN A3 (5V): -0.002670
CHAN B0 (3.3V): -0.001434
CHAN B1 (3.15aV): -0.001152
CHAN B2 (3.15bV): -0.000884
CHAN B3 (GND): -0.000610
OPEN: -0.004479
Full Scale: +/- 1 mA
2024-07-19T02:02:13.662Z,1721354533.662 [NavChartDb](INFO): # of records loaded: 65000
2024-07-19T02:02:16.256Z,1721354536.256 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:02:21.084Z,1721354541.084 [NavChartDb](INFO): # of records loaded: 70000
2024-07-19T02:02:28.759Z,1721354548.759 [NavChartDb](INFO): # of records loaded: 75000
2024-07-19T02:02:30.497Z,1721354550.497 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T02:02:36.623Z,1721354556.623 [NavChartDb](INFO): # of records loaded: 80000
2024-07-19T02:02:44.943Z,1721354564.943 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:02:45.519Z,1721354565.519 [NavChartDb](INFO): # of records loaded: 85000
2024-07-19T02:02:53.361Z,1721354573.361 [NavChartDb](INFO): # of records loaded: 90000
2024-07-19T02:02:53.937Z,1721354573.937 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:02:55.889Z,1721354575.889 [SBIT](IMPORTANT): SBIT PASSED
2024-07-19T02:02:55.889Z,1721354575.889 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-19T02:02:55.890Z,1721354575.890 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=8 count;
2024-07-19T02:02:55.890Z,1721354575.890 [SBIT](IMPORTANT): CTD_Seabird.minSalinityBound=0 practical_salinity_unit;
2024-07-19T02:02:55.890Z,1721354575.890 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-19T02:02:55.890Z,1721354575.890 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=250 cubic_centimeter;
2024-07-19T02:02:55.890Z,1721354575.890 [SBIT](IMPORTANT): VerticalControl.massDefault=2.25 centimeter;
2024-07-19T02:02:56.294Z,1721354576.294 [MissionManager](IMPORTANT): Started mission Startup
2024-07-19T02:02:56.294Z,1721354576.294 [Startup] Running Loop=1
2024-07-19T02:02:56.294Z,1721354576.294 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-19T02:02:56.294Z,1721354576.294 [Startup:A.GoToSurface] Running Loop=1
2024-07-19T02:02:56.294Z,1721354576.294 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-19T02:02:56.295Z,1721354576.295 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-19T02:02:56.295Z,1721354576.295 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-19T02:02:56.300Z,1721354576.300 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-19T02:02:56.301Z,1721354576.301 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-19T02:02:56.301Z,1721354576.301 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-19T02:02:56.301Z,1721354576.301 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-19T02:02:56.303Z,1721354576.303 [Startup:StartupSatComms] Running Loop=1
2024-07-19T02:02:56.303Z,1721354576.303 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-19T02:02:56.303Z,1721354576.303 [Startup:StartupSatComms:A] Running Loop=1
2024-07-19T02:02:56.681Z,1721354576.681 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-19T02:02:59.889Z,1721354579.889 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:03:00.919Z,1721354580.919 [NavChartDb](INFO): # of records loaded: 95000
2024-07-19T02:03:08.980Z,1721354588.980 [NavChartDb](INFO): # of records loaded: 100000
2024-07-19T02:03:11.220Z,1721354591.220 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-19T02:03:11.220Z,1721354591.220 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-19T02:03:11.263Z,1721354591.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-19T02:03:11.663Z,1721354591.663 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-19T02:03:11.663Z,1721354591.663 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-19T02:03:14.029Z,1721354594.029 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:03:16.826Z,1721354596.826 [NavChartDb](INFO): # of records loaded: 105000
2024-07-19T02:03:24.850Z,1721354604.850 [NavChartDb](INFO): # of records loaded: 110000
2024-07-19T02:03:28.573Z,1721354608.573 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2024-07-19T02:03:39.160Z,1721354619.160 [NavChartDb](INFO): # of records loaded: 115000
2024-07-19T02:03:51.061Z,1721354631.061 [NavChartDb](INFO): # of records loaded: 120000
2024-07-19T02:03:51.928Z,1721354631.928 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003037
2024-07-19T02:03:56.436Z,1721354636.436 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-19T02:02:56.3Z
2024-07-19T02:03:56.436Z,1721354636.436 [Startup:StartupSatComms:A] Stopped
2024-07-19T02:03:56.437Z,1721354636.437 [Startup:StartupSatComms:B] Running Loop=1
2024-07-19T02:03:56.696Z,1721354636.696 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-19T02:03:58.915Z,1721354638.915 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:04:03.002Z,1721354643.002 [NavChartDb](INFO): # of records loaded: 125000
2024-07-19T02:04:13.581Z,1721354653.581 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20240719T015408/Courier0000.lzma
2024-07-19T02:04:14.582Z,1721354654.582 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015408/Courier0000.lzma.bak
2024-07-19T02:04:14.582Z,1721354654.582 [DataOverHttps](INFO): SBD MOMSN=21368541
2024-07-19T02:04:15.966Z,1721354655.966 [NavChartDb](INFO): # of records loaded: 130000
2024-07-19T02:04:40.276Z,1721354680.276 [NavChartDb](INFO): # of records loaded: 135000
2024-07-19T02:04:43.545Z,1721354683.545 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:04:54.869Z,1721354694.869 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240719T015408/Courier0004.lzma
2024-07-19T02:04:55.870Z,1721354695.870 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015408/Courier0004.lzma.bak
2024-07-19T02:04:55.870Z,1721354695.870 [DataOverHttps](INFO): SBD MOMSN=21368546
2024-07-19T02:04:56.542Z,1721354696.542 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-19T02:03:56.4Z
2024-07-19T02:04:56.542Z,1721354696.542 [Startup:StartupSatComms:B] Stopped
2024-07-19T02:04:56.542Z,1721354696.542 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-19T02:04:56.542Z,1721354696.542 [Startup:StartupSatComms] Stopped
2024-07-19T02:04:56.542Z,1721354696.542 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-19T02:04:56.543Z,1721354696.543 [Startup](INFO): Completed Startup
2024-07-19T02:04:56.543Z,1721354696.543 [MissionManager](INFO): Startup is completed.
2024-07-19T02:04:56.543Z,1721354696.543 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-19T02:04:56.543Z,1721354696.543 [Startup] Stopped
2024-07-19T02:04:56.543Z,1721354696.543 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-19T02:04:56.608Z,1721354696.608 [Startup:A.GoToSurface] Stopped
2024-07-19T02:04:56.608Z,1721354696.608 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-19T02:04:56.770Z,1721354696.770 [MissionManager](IMPORTANT): Started mission Default
2024-07-19T02:04:56.770Z,1721354696.770 [Default] Running Loop=1
2024-07-19T02:04:56.770Z,1721354696.770 [Default](DEBUG): Aggregate::initialize Default
2024-07-19T02:04:56.770Z,1721354696.770 [Default:B.GoToSurface] Running Loop=1
2024-07-19T02:04:56.771Z,1721354696.771 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-19T02:04:56.771Z,1721354696.771 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-19T02:04:56.771Z,1721354696.771 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-19T02:04:56.771Z,1721354696.771 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-19T02:04:56.780Z,1721354696.780 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-19T02:04:56.780Z,1721354696.780 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-19T02:04:56.781Z,1721354696.781 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-19T02:04:56.781Z,1721354696.781 [Default:A.Wait] Running Loop=1
2024-07-19T02:04:56.781Z,1721354696.781 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-19T02:05:02.738Z,1721354702.738 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:05:02.738Z,1721354702.738 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:05:02.864Z,1721354702.864 [NavChartDb](INFO): # of records loaded: 140000
2024-07-19T02:05:09.844Z,1721354709.844 [Default:A.Wait](INFO): Done Waiting.
2024-07-19T02:05:09.844Z,1721354709.844 [Default:A.Wait] Stopped
2024-07-19T02:05:09.844Z,1721354709.844 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T02:05:10.494Z,1721354710.494 [Default:CheckIn] Running Loop=1
2024-07-19T02:05:10.494Z,1721354710.494 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T02:05:10.494Z,1721354710.494 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T02:05:10.808Z,1721354710.808 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-19T02:05:19.577Z,1721354719.577 [NavChartDb](INFO): # of records loaded: 145000
2024-07-19T02:05:30.777Z,1721354730.777 [NavChartDb](INFO): # of records loaded: 150000
2024-07-19T02:05:43.847Z,1721354743.847 [NavChartDb](INFO): # of records loaded: 155000
2024-07-19T02:05:54.269Z,1721354754.269 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-19T02:05:55.483Z,1721354755.483 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,020553.00,A,4538.83685,N,08428.35602,W,0.700,0.00,190724,,,A*7D
2024-07-19T02:05:55.493Z,1721354755.493 [NAL9602](INFO): GPS fix at 20240719T020553: (45.647281, -84.472600)
2024-07-19T02:05:55.525Z,1721354755.525 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T02:05:55.525Z,1721354755.525 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T02:05:55.944Z,1721354755.944 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-19T02:05:59.718Z,1721354759.718 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:06:01.504Z,1721354761.504 [NavChartDb](INFO): # of records loaded: 160000
2024-07-19T02:06:10.031Z,1721354770.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:06:10.256Z,1721354770.256 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240719T015959/Courier0000.lzma
2024-07-19T02:06:11.258Z,1721354771.258 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Courier0000.lzma.bak
2024-07-19T02:06:11.258Z,1721354771.258 [DataOverHttps](INFO): SBD MOMSN=21368561
2024-07-19T02:06:22.131Z,1721354782.131 [NavChartDb](INFO): # of records loaded: 165000
2024-07-19T02:06:26.283Z,1721354786.283 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:06:27.425Z,1721354787.425 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-19T02:06:38.228Z,1721354798.228 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20240719T015959/Courier0004.lzma
2024-07-19T02:06:39.230Z,1721354799.230 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Courier0004.lzma.bak
2024-07-19T02:06:39.230Z,1721354799.230 [DataOverHttps](INFO): SBD MOMSN=21368566
2024-07-19T02:06:42.837Z,1721354802.837 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-07-19T02:06:53.858Z,1721354813.858 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T02:06:59.742Z,1721354819.742 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:07:11.282Z,1721354831.282 [NavChartDb](INFO): # of records loaded: 170000
2024-07-19T02:07:13.246Z,1721354833.246 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US5MI61M.000
2024-07-19T02:07:13.286Z,1721354833.286 [NavChartDb](INFO): # of records loaded: 170330
2024-07-19T02:07:13.316Z,1721354833.316 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5MI61M.000
2024-07-19T02:07:13.317Z,1721354833.317 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:07:13.754Z,1721354833.754 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:07:16.319Z,1721354836.319 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:07:18.972Z,1721354838.972 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20240719T013834/Express0008.lzma
2024-07-19T02:07:19.979Z,1721354839.979 [DataOverHttps](INFO): Moved sent file to Logs/20240719T013834/Express0008.lzma.bak
2024-07-19T02:07:19.979Z,1721354839.979 [DataOverHttps](INFO): SBD MOMSN=21368573
2024-07-19T02:07:23.119Z,1721354843.119 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:07:24.085Z,1721354844.085 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:07:25.245Z,1721354845.245 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1MF.000
2024-07-19T02:07:25.319Z,1721354845.319 [NavChartDb](INFO): # of records loaded: 4559
2024-07-19T02:07:25.325Z,1721354845.325 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1MF.000
2024-07-19T02:07:25.349Z,1721354845.349 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:07:25.768Z,1721354845.768 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:07:33.628Z,1721354853.628 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:07:33.702Z,1721354853.702 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:07:37.746Z,1721354857.746 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:07:40.221Z,1721354860.221 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1NF.000
2024-07-19T02:07:40.300Z,1721354860.300 [NavChartDb](INFO): # of records loaded: 4667
2024-07-19T02:07:40.302Z,1721354860.302 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1NF.000
2024-07-19T02:07:40.320Z,1721354860.320 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:07:43.083Z,1721354863.083 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:07:50.263Z,1721354870.263 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:07:53.049Z,1721354873.049 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:07:53.947Z,1721354873.947 [DataOverHttps](INFO): Sending 1094 bytes from file Logs/20240719T015408/Express0001.lzma
2024-07-19T02:07:54.946Z,1721354874.946 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015408/Express0001.lzma.bak
2024-07-19T02:07:54.946Z,1721354874.946 [DataOverHttps](INFO): SBD MOMSN=21368596
2024-07-19T02:08:05.435Z,1721354885.435 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T02:08:06.835Z,1721354886.835 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-19T02:08:21.554Z,1721354901.554 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T02:08:23.016Z,1721354903.016 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-19T02:08:33.955Z,1721354913.955 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T02:08:35.417Z,1721354915.417 [DataOverHttps](INFO): Sending 114 bytes from file Logs/20240719T015408/Express0005.lzma
2024-07-19T02:08:36.418Z,1721354916.418 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015408/Express0005.lzma.bak
2024-07-19T02:08:36.418Z,1721354916.418 [DataOverHttps](INFO): SBD MOMSN=21368624
2024-07-19T02:08:39.162Z,1721354919.162 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2024-07-19T02:08:39.169Z,1721354919.169 [BPC1](INFO): Calculating totals. Valid battery stick count: 51. Valid reserve battery stick count: 5.
2024-07-19T02:08:39.192Z,1721354919.192 [BPC1](INFO): Received data from all battery sticks.
2024-07-19T02:08:43.962Z,1721354923.962 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T02:08:48.951Z,1721354928.951 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T02:08:59.120Z,1721354939.120 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T02:08:59.232Z,1721354939.232 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:09:08.284Z,1721354948.284 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:09:09.816Z,1721354949.816 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T02:09:12.341Z,1721354952.341 [DataOverHttps](INFO): Sending 959 bytes from file Logs/20240719T015959/Express0001.lzma
2024-07-19T02:09:13.342Z,1721354953.342 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Express0001.lzma.bak
2024-07-19T02:09:13.342Z,1721354953.342 [DataOverHttps](INFO): SBD MOMSN=21368628
2024-07-19T02:09:17.005Z,1721354957.005 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OF.000
2024-07-19T02:09:17.068Z,1721354957.068 [NavChartDb](INFO): # of records loaded: 42018
2024-07-19T02:09:17.086Z,1721354957.086 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OF.000
2024-07-19T02:09:17.104Z,1721354957.104 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:09:20.279Z,1721354960.279 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:09:35.716Z,1721354975.716 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:09:45.080Z,1721354985.080 [DataOverHttps](INFO): Sending 321 bytes from file Logs/20240719T015959/Express0005.lzma
2024-07-19T02:09:46.082Z,1721354986.082 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Express0005.lzma.bak
2024-07-19T02:09:46.082Z,1721354986.082 [DataOverHttps](INFO): SBD MOMSN=21368655
2024-07-19T02:09:48.421Z,1721354988.421 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T02:09:57.633Z,1721354997.633 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T02:09:57.634Z,1721354997.634 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T02:09:57.634Z,1721354997.634 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T02:10:04.287Z,1721355004.287 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T02:10:13.525Z,1721355013.525 [Power24vConverter](INFO): Powering down.
2024-07-19T02:10:18.913Z,1721355018.913 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T02:10:30.171Z,1721355030.171 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T02:10:38.234Z,1721355038.234 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T02:10:44.843Z,1721355044.843 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:10:51.921Z,1721355051.921 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T02:11:04.044Z,1721355064.044 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:11:11.547Z,1721355071.547 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T02:11:13.967Z,1721355073.967 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI51M.000
2024-07-19T02:11:14.054Z,1721355074.054 [NavChartDb](INFO): # of records loaded: 40792
2024-07-19T02:11:14.059Z,1721355074.059 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI51M.000
2024-07-19T02:11:14.060Z,1721355074.060 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:11:14.497Z,1721355074.497 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:11:22.420Z,1721355082.420 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:11:30.586Z,1721355090.586 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:11:31.645Z,1721355091.645 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:11:33.080Z,1721355093.080 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1OD.000
2024-07-19T02:11:33.138Z,1721355093.138 [NavChartDb](INFO): # of records loaded: 9682
2024-07-19T02:11:33.140Z,1721355093.140 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1OD.000
2024-07-19T02:11:33.172Z,1721355093.172 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:11:34.656Z,1721355094.656 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:11:45.437Z,1721355105.437 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:11:52.799Z,1721355112.799 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T02:12:00.930Z,1721355120.930 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:12:00.949Z,1721355120.949 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T02:12:06.114Z,1721355126.114 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:12:10.096Z,1721355130.096 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US4MI1ND.000
2024-07-19T02:12:10.225Z,1721355130.225 [NavChartDb](INFO): # of records loaded: 19081
2024-07-19T02:12:10.232Z,1721355130.232 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US4MI1ND.000
2024-07-19T02:12:10.233Z,1721355130.233 [NavChartDb](INFO): Setup scan of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:12:14.639Z,1721355134.639 [NavChartDb](INFO): Done scanning features of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:12:23.623Z,1721355143.623 [NavChartDb](INFO): # of records loaded: 5000
2024-07-19T02:12:33.538Z,1721355153.538 [NavChartDb](INFO): # of records loaded: 10000
2024-07-19T02:12:48.483Z,1721355168.483 [NavChartDb](INFO): # of records loaded: 15000
2024-07-19T02:12:57.861Z,1721355177.861 [NavChartDb](INFO): # of records loaded: 20000
2024-07-19T02:13:03.967Z,1721355183.967 [NavChartDb](INFO): # of records loaded: 25000
2024-07-19T02:13:15.354Z,1721355195.354 [NavChartDb](INFO): # of records loaded: 30000
2024-07-19T02:13:23.446Z,1721355203.446 [NavChartDb](INFO): # of records loaded: 35000
2024-07-19T02:13:31.017Z,1721355211.017 [NavChartDb](INFO): # of records loaded: 40000
2024-07-19T02:13:36.023Z,1721355216.023 [NavChartDb](INFO): # of records loaded: 45000
2024-07-19T02:13:37.881Z,1721355217.881 [NavChartDb](INFO): Calculating coverage of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:13:42.764Z,1721355222.764 [NavChartDb](INFO): # of records loaded: 50000
2024-07-19T02:13:51.723Z,1721355231.723 [NavChartDb](INFO): # of records loaded: 55000
2024-07-19T02:14:02.406Z,1721355242.406 [NavChartDb](INFO): # of records loaded: 60000
2024-07-19T02:14:06.083Z,1721355246.083 [NavChartDb](INFO): Done scanning edges of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:14:15.783Z,1721355255.783 [NavChartDb](INFO): Done scanning nodes of Resources/ElectronicNavigationCharts/US2MI60M.000
2024-07-19T02:14:16.307Z,1721355256.307 [NavChartDb](INFO): # of records loaded: 64544
2024-07-19T02:14:16.313Z,1721355256.313 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2MI60M.000
2024-07-19T02:14:17.891Z,1721355257.891 [NavChartDb](INFO): Creating index for soundings
2024-07-19T02:14:42.853Z,1721355282.853 [NavChartDb](INFO): Creating index for 0p0
2024-07-19T02:14:58.125Z,1721355298.125 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-19T02:14:58.125Z,1721355298.125 [Default:CheckIn:C.Wait] Stopped
2024-07-19T02:14:58.126Z,1721355298.126 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T02:14:58.126Z,1721355298.126 [Default:CheckIn:D] Running Loop=1
2024-07-19T02:14:58.498Z,1721355298.498 [Default:CheckIn:D] Stopped
2024-07-19T02:14:58.498Z,1721355298.498 [Default:CheckIn:E] Running Loop=1
2024-07-19T02:14:58.926Z,1721355298.926 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.028795 min
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn:E] Stopped
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn] Stopped
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn](INFO): Running loop #2
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn] Running Loop=2
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T02:14:58.927Z,1721355298.927 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T02:14:59.030Z,1721355299.030 [CommandExec](IMPORTANT): got command get latitude
2024-07-19T02:14:59.031Z,1721355299.031 [CommandExec](IMPORTANT): latitude 45.647281 arcdeg
2024-07-19T02:15:00.907Z,1721355300.907 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,021500.00,A,4538.82457,N,08428.35342,W,0.039,28.42,190724,,,A*46
2024-07-19T02:15:00.909Z,1721355300.909 [NAL9602](INFO): GPS fix at 20240719T021500: (45.647076, -84.472557)
2024-07-19T02:15:00.956Z,1721355300.956 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T02:15:00.956Z,1721355300.956 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T02:15:02.665Z,1721355302.665 [NavChartDb](INFO): Creating index for 1p8
2024-07-19T02:15:18.674Z,1721355318.674 [NavChartDb](INFO): Creating index for 3p6
2024-07-19T02:15:21.079Z,1721355321.079 [DataOverHttps](INFO): Sending 201 bytes from file Logs/20240719T015959/Courier0007.lzma
2024-07-19T02:15:22.082Z,1721355322.082 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Courier0007.lzma.bak
2024-07-19T02:15:22.082Z,1721355322.082 [DataOverHttps](INFO): SBD MOMSN=21368660
2024-07-19T02:15:29.897Z,1721355329.897 [NavChartDb](INFO): Creating index for 5p4
2024-07-19T02:15:48.064Z,1721355348.064 [NavChartDb](INFO): Creating index for 7p3
2024-07-19T02:15:55.800Z,1721355355.800 [DataOverHttps](INFO): Sending 339 bytes from file Logs/20240719T015959/Express0008.lzma
2024-07-19T02:15:56.802Z,1721355356.802 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Express0008.lzma.bak
2024-07-19T02:15:56.802Z,1721355356.802 [DataOverHttps](INFO): SBD MOMSN=21368663
2024-07-19T02:16:04.423Z,1721355364.423 [NavChartDb](INFO): Creating index for 9p1
2024-07-19T02:16:08.766Z,1721355368.766 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T02:16:08.766Z,1721355368.766 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T02:16:08.766Z,1721355368.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T02:16:23.683Z,1721355383.683 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:16:23.683Z,1721355383.683 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:16:54.825Z,1721355414.825 [CommandExec](IMPORTANT): got command show variable distance
2024-07-19T02:16:54.828Z,1721355414.828 [CommandExec](IMPORTANT): distance_from_shore (meter)
2024-07-19T02:16:54.829Z,1721355414.829 [CommandExec](IMPORTANT): fix_distance_made_good (unknown)
2024-07-19T02:16:54.829Z,1721355414.829 [CommandExec](IMPORTANT): fix_residual_distance (unknown)
2024-07-19T02:16:54.830Z,1721355414.830 [CommandExec](IMPORTANT): fix_residual_percent_distance_traveled (unknown)
2024-07-19T02:16:54.831Z,1721355414.831 [CommandExec](IMPORTANT): platform_distance_wrt_ground (unknown)
2024-07-19T02:16:54.831Z,1721355414.831 [CommandExec](IMPORTANT): platform_distance_wrt_sea_water (meter)
2024-07-19T02:16:54.868Z,1721355414.868 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup (bool)
2024-07-19T02:16:54.876Z,1721355414.876 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.verbosity (count)
2024-07-19T02:16:54.877Z,1721355414.877 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex1 (degree)
2024-07-19T02:16:54.877Z,1721355414.877 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex1 (degree)
2024-07-19T02:16:54.877Z,1721355414.877 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex2 (degree)
2024-07-19T02:16:54.878Z,1721355414.878 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex2 (degree)
2024-07-19T02:16:54.878Z,1721355414.878 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex3 (degree)
2024-07-19T02:16:54.878Z,1721355414.878 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex3 (degree)
2024-07-19T02:16:54.879Z,1721355414.879 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex4 (degree)
2024-07-19T02:16:54.879Z,1721355414.879 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex4 (degree)
2024-07-19T02:16:54.879Z,1721355414.879 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex5 (degree)
2024-07-19T02:16:54.881Z,1721355414.881 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex5 (degree)
2024-07-19T02:16:54.881Z,1721355414.881 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex6 (degree)
2024-07-19T02:16:54.881Z,1721355414.881 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex6 (degree)
2024-07-19T02:16:54.882Z,1721355414.882 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex7 (degree)
2024-07-19T02:16:54.882Z,1721355414.882 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex7 (degree)
2024-07-19T02:16:54.882Z,1721355414.882 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex8 (degree)
2024-07-19T02:16:54.883Z,1721355414.883 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex8 (degree)
2024-07-19T02:16:54.883Z,1721355414.883 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex9 (degree)
2024-07-19T02:16:54.883Z,1721355414.883 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex9 (degree)
2024-07-19T02:16:54.884Z,1721355414.884 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.latVertex10 (degree)
2024-07-19T02:16:54.884Z,1721355414.884 [CommandExec](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.lonVertex10 (degree)
2024-07-19T02:16:54.886Z,1721355414.886 [CommandExec](IMPORTANT): LineCapture.rolloutDistance (meter)
2024-07-19T02:16:54.958Z,1721355414.958 [CommandExec](IMPORTANT): MassServo.deviationDistance (millimeter)
2024-07-19T02:16:55.069Z,1721355415.069 [CommandExec](IMPORTANT): GobyModem.maxDistance (kilometer)
2024-07-19T02:16:55.075Z,1721355415.075 [CommandExec](IMPORTANT): Rowe_600.acousticBlankingDistance (meter)
2024-07-19T02:16:55.126Z,1721355415.126 [CommandExec](IMPORTANT): SpeedCalculator.platform_distance_wrt_sea_water (meter)
2024-07-19T02:16:55.127Z,1721355415.127 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_distance_made_good (meter)
2024-07-19T02:16:55.128Z,1721355415.128 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_distance (meter)
2024-07-19T02:16:55.129Z,1721355415.129 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_percent_distance_traveled (percent)
2024-07-19T02:16:55.130Z,1721355415.130 [CommandExec](IMPORTANT): NavChart.distance_from_shore (meter)
2024-07-19T02:16:55.242Z,1721355415.242 [CommandExec](IMPORTANT): NavChartDb.closestDistance (meter)
2024-07-19T02:16:55.242Z,1721355415.242 [CommandExec](IMPORTANT): NavChartDb.nextDistance (meter)
2024-07-19T02:17:12.159Z,1721355432.159 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-19T02:17:12.160Z,1721355432.160 [CommandExec](IMPORTANT): NavChart.distance_from_shore 58.896397 m
2024-07-19T02:18:56.027Z,1721355536.027 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:18:56.027Z,1721355536.027 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:19:29.123Z,1721355569.123 [CommandExec](IMPORTANT): got command failComponent
2024-07-19T02:19:29.124Z,1721355569.124 [CommandExec](IMPORTANT): Failed components:
2024-07-19T02:19:29.124Z,1721355569.124 [CommandExec](IMPORTANT): No failed Components.
2024-07-19T02:20:01.879Z,1721355601.879 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:20:01.879Z,1721355601.879 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:20:03.493Z,1721355603.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-19T02:20:34.196Z,1721355634.196 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-19T02:21:09.354Z,1721355669.354 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-19T02:21:09.355Z,1721355669.355 [Default:CheckIn:C.Wait] Stopped
2024-07-19T02:21:09.355Z,1721355669.355 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T02:21:09.355Z,1721355669.355 [Default:CheckIn:D] Running Loop=1
2024-07-19T02:21:09.763Z,1721355669.763 [Default:CheckIn:D] Stopped
2024-07-19T02:21:09.763Z,1721355669.763 [Default:CheckIn:E] Running Loop=1
2024-07-19T02:21:10.162Z,1721355670.162 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.216547 min
2024-07-19T02:21:10.162Z,1721355670.162 [Default:CheckIn:E] Stopped
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn] Stopped
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn](INFO): Running loop #3
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn] Running Loop=3
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T02:21:10.163Z,1721355670.163 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T02:21:12.183Z,1721355672.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022111.00,A,4538.82274,N,08428.35083,W,0.525,310.86,190724,,,A*70
2024-07-19T02:21:12.193Z,1721355672.193 [NAL9602](INFO): GPS fix at 20240719T022111: (45.647046, -84.472514)
2024-07-19T02:21:12.211Z,1721355672.211 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T02:21:12.211Z,1721355672.211 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T02:21:17.723Z,1721355677.723 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240719T015959/Courier0010.lzma
2024-07-19T02:21:18.730Z,1721355678.730 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Courier0010.lzma.bak
2024-07-19T02:21:18.730Z,1721355678.730 [DataOverHttps](INFO): SBD MOMSN=21368676
2024-07-19T02:21:42.981Z,1721355702.981 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-19T02:21:43.056Z,1721355703.056 [NAL9602](ERROR): received:
+SBDI: 2, 5276, 2, 0, 0, 0
OK
2024-07-19T02:21:43.882Z,1721355703.882 [DataOverHttps](INFO): Sending 796 bytes from file Logs/20240719T015959/Express0011.lzma
2024-07-19T02:21:44.882Z,1721355704.882 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Express0011.lzma.bak
2024-07-19T02:21:44.882Z,1721355704.882 [DataOverHttps](INFO): SBD MOMSN=21368679
2024-07-19T02:21:48.797Z,1721355708.797 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-19T02:21:48.798Z,1721355708.798 [CommandExec](IMPORTANT): NavChart.distance_from_shore 60.583164 m
2024-07-19T02:21:52.719Z,1721355712.719 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T02:21:52.719Z,1721355712.719 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T02:21:52.724Z,1721355712.724 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T02:22:40.754Z,1721355760.754 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:22:40.754Z,1721355760.754 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:23:06.606Z,1721355786.606 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:23:06.606Z,1721355786.606 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:23:39.336Z,1721355819.336 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:23:39.336Z,1721355819.336 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:23:55.094Z,1721355835.094 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:23:55.094Z,1721355835.094 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:24:36.730Z,1721355876.730 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:24:36.730Z,1721355876.730 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:26:16.126Z,1721355976.126 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:26:16.126Z,1721355976.126 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:26:16.501Z,1721355976.501 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-19T02:26:47.225Z,1721356007.225 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-19T02:26:53.302Z,1721356013.302 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-19T02:26:53.302Z,1721356013.302 [Default:CheckIn:C.Wait] Stopped
2024-07-19T02:26:53.302Z,1721356013.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T02:26:53.302Z,1721356013.302 [Default:CheckIn:D] Running Loop=1
2024-07-19T02:26:53.707Z,1721356013.707 [Default:CheckIn:D] Stopped
2024-07-19T02:26:53.707Z,1721356013.707 [Default:CheckIn:E] Running Loop=1
2024-07-19T02:26:54.120Z,1721356014.120 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.948940 min
2024-07-19T02:26:54.120Z,1721356014.120 [Default:CheckIn:E] Stopped
2024-07-19T02:26:54.120Z,1721356014.120 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-19T02:26:54.120Z,1721356014.120 [Default:CheckIn] Stopped
2024-07-19T02:26:54.121Z,1721356014.121 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T02:26:54.121Z,1721356014.121 [Default:CheckIn](INFO): Running loop #4
2024-07-19T02:26:54.121Z,1721356014.121 [Default:CheckIn] Running Loop=4
2024-07-19T02:26:54.121Z,1721356014.121 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-19T02:26:54.121Z,1721356014.121 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-19T02:26:56.115Z,1721356016.115 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,022655.00,A,4538.82554,N,08428.35955,W,0.058,310.86,190724,,,A*7F
2024-07-19T02:26:56.117Z,1721356016.117 [NAL9602](INFO): GPS fix at 20240719T022655: (45.647092, -84.472659)
2024-07-19T02:26:56.147Z,1721356016.147 [Default:CheckIn:Read_GPS] Stopped
2024-07-19T02:26:56.147Z,1721356016.147 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-19T02:27:08.311Z,1721356028.311 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240719T015959/Courier0013.lzma
2024-07-19T02:27:09.314Z,1721356029.314 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Courier0013.lzma.bak
2024-07-19T02:27:09.314Z,1721356029.314 [DataOverHttps](INFO): SBD MOMSN=21368728
2024-07-19T02:27:33.332Z,1721356053.332 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20240719T015959/Express0014.lzma
2024-07-19T02:27:34.334Z,1721356054.334 [DataOverHttps](INFO): Moved sent file to Logs/20240719T015959/Express0014.lzma.bak
2024-07-19T02:27:34.334Z,1721356054.334 [DataOverHttps](INFO): SBD MOMSN=21368731
2024-07-19T02:27:41.000Z,1721356061.000 [Default:CheckIn:Read_Iridium] Stopped
2024-07-19T02:27:41.000Z,1721356061.000 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-19T02:27:41.001Z,1721356061.001 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-19T02:27:42.998Z,1721356062.998 [NAL9602](INFO): SBD MO Status=2, MOMSN=5276, MT Status=2, MTMSN=0
2024-07-19T02:27:42.998Z,1721356062.998 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-19T02:28:32.472Z,1721356112.472 [CommandExec](IMPORTANT): got command failComponent
2024-07-19T02:28:32.473Z,1721356112.473 [CommandExec](IMPORTANT): Failed components:
2024-07-19T02:28:32.473Z,1721356112.473 [CommandExec](IMPORTANT): No failed Components.
2024-07-19T02:28:40.611Z,1721356120.611 [CommandExec](IMPORTANT): got command get NavChart.distance_from_shore
2024-07-19T02:28:40.612Z,1721356120.612 [CommandExec](IMPORTANT): NavChart.distance_from_shore 61.465488 m
2024-07-19T02:30:23.317Z,1721356223.317 [DataOverHttps](IMPORTANT): SBD MTMSN=20240719T023022
2024-07-19T02:30:30.700Z,1721356230.700 [DataOverHttps](INFO): Received command: restart app
2024-07-19T02:30:30.785Z,1721356230.785 [CommandExec](IMPORTANT): got command restart application
2024-07-19T02:30:31.788Z,1721356231.788 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2024-07-19T02:30:31.788Z,1721356231.788 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:31.788Z,1721356231.788 [CommandExec](INFO): Uninitializing the command executive.
2024-07-19T02:30:31.788Z,1721356231.788 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-19T02:30:31.789Z,1721356231.789 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:31.976Z,1721356231.976 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-19T02:30:31.976Z,1721356231.976 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-19T02:30:31.976Z,1721356231.976 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:31.977Z,1721356231.977 [NavChartDb](INFO): Join timeout helper Thread ID is 1116
2024-07-19T02:30:32.020Z,1721356232.020 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:32.020Z,1721356232.020 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:32.036Z,1721356232.036 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-19T02:30:32.036Z,1721356232.036 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:32.037Z,1721356232.037 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1117
2024-07-19T02:30:32.244Z,1721356232.244 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:32.245Z,1721356232.245 [WetLabsBB2FL](INFO): Powering down
2024-07-19T02:30:32.245Z,1721356232.245 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:32.265Z,1721356232.265 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-19T02:30:32.265Z,1721356232.265 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:32.265Z,1721356232.265 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1118
2024-07-19T02:30:33.108Z,1721356233.108 [CTD_Seabird](INFO): Powering down
2024-07-19T02:30:33.120Z,1721356233.120 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:33.120Z,1721356233.120 [CTD_Seabird](INFO): Powering down
2024-07-19T02:30:33.132Z,1721356233.132 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:33.148Z,1721356233.148 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-19T02:30:33.148Z,1721356233.148 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:33.148Z,1721356233.148 [Radio_Surface](INFO): Join timeout helper Thread ID is 1119
2024-07-19T02:30:33.204Z,1721356233.204 [Radio_Surface](INFO): Powering down
2024-07-19T02:30:33.205Z,1721356233.205 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:33.205Z,1721356233.205 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:33.222Z,1721356233.222 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-19T02:30:33.222Z,1721356233.222 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:33.223Z,1721356233.223 [Onboard](INFO): Join timeout helper Thread ID is 1120
2024-07-19T02:30:33.708Z,1721356233.708 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2024-07-19T02:30:37.956Z,1721356237.956 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:37.956Z,1721356237.956 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:37.969Z,1721356237.969 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-19T02:30:37.969Z,1721356237.969 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:37.969Z,1721356237.969 [DataOverHttps](INFO): Join timeout helper Thread ID is 1121
2024-07-19T02:30:38.728Z,1721356238.728 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:38.728Z,1721356238.728 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.741Z,1721356238.741 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-19T02:30:38.741Z,1721356238.741 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.744Z,1721356238.744 [BackseatComponent](INFO): Join timeout helper Thread ID is 1122
2024-07-19T02:30:38.828Z,1721356238.828 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:38.828Z,1721356238.828 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.845Z,1721356238.845 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-19T02:30:38.845Z,1721356238.845 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.845Z,1721356238.845 [logger](INFO): Join timeout helper Thread ID is 1123
2024-07-19T02:30:38.856Z,1721356238.856 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:38.856Z,1721356238.856 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.864Z,1721356238.864 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-19T02:30:38.865Z,1721356238.865 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.865Z,1721356238.865 [CommandLine](INFO): Join timeout helper Thread ID is 1124
2024-07-19T02:30:38.944Z,1721356238.944 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:38.944Z,1721356238.944 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.964Z,1721356238.964 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-19T02:30:38.965Z,1721356238.965 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.965Z,1721356238.965 [CommandExec](INFO): Join timeout helper Thread ID is 1125
2024-07-19T02:30:38.966Z,1721356238.966 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-19T02:30:38.966Z,1721356238.966 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:38.967Z,1721356238.967 [controlThread](INFO): Join timeout helper Thread ID is 1126
2024-07-19T02:30:39.128Z,1721356239.128 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-19T02:30:39.128Z,1721356239.128 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-19T02:30:39.129Z,1721356239.129 [AHRS_M2](INFO): Powering down
2024-07-19T02:30:39.201Z,1721356239.201 [NAL9602](INFO): Powering down
2024-07-19T02:30:39.202Z,1721356239.202 [Sonardyne_Nano](INFO): Powering down
2024-07-19T02:30:39.321Z,1721356239.321 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-19T02:30:39.321Z,1721356239.321 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-19T02:30:39.322Z,1721356239.322 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-19T02:30:39.322Z,1721356239.322 [MissionManager](INFO): Uninitializing Mission Default
2024-07-19T02:30:39.322Z,1721356239.322 [Default] Stopped
2024-07-19T02:30:39.322Z,1721356239.322 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-19T02:30:39.323Z,1721356239.323 [Default:B.GoToSurface] Stopped
2024-07-19T02:30:39.323Z,1721356239.323 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-19T02:30:39.323Z,1721356239.323 [Default:CheckIn] Stopped
2024-07-19T02:30:39.323Z,1721356239.323 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-19T02:30:39.323Z,1721356239.323 [Default:CheckIn:C.Wait] Stopped
2024-07-19T02:30:39.323Z,1721356239.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-19T02:30:39.326Z,1721356239.326 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-19T02:30:39.326Z,1721356239.326 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-19T02:30:39.326Z,1721356239.326 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-19T02:30:39.327Z,1721356239.327 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-19T02:30:39.327Z,1721356239.327 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-19T02:30:39.327Z,1721356239.327 [BuoyancyServo](INFO): Powering down
2024-07-19T02:30:39.340Z,1721356239.340 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-19T02:30:39.340Z,1721356239.340 [ElevatorServo](INFO): Powering down
2024-07-19T02:30:39.341Z,1721356239.341 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-19T02:30:39.341Z,1721356239.341 [MassServo](INFO): Powering down
2024-07-19T02:30:39.342Z,1721356239.342 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-19T02:30:39.342Z,1721356239.342 [RudderServo](INFO): Powering down
2024-07-19T02:30:39.343Z,1721356239.343 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-19T02:30:39.343Z,1721356239.343 [ThrusterHE](INFO): Powering down
2024-07-19T02:30:39.343Z,1721356239.343 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-19T02:30:39.344Z,1721356239.344 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-19T02:30:39.344Z,1721356239.344 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-19T02:30:39.344Z,1721356239.344 [CBIT](DEBUG): Powering off loads.
2024-07-19T02:30:39.356Z,1721356239.356 [CBIT](DEBUG): Disabling WDT.
2024-07-19T02:30:39.368Z,1721356239.368 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-19T02:30:39.369Z,1721356239.369 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.425Z,1721356239.425 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.428Z,1721356239.428 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.435Z,1721356239.435 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.479Z,1721356239.479 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.518Z,1721356239.518 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.522Z,1721356239.522 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.555Z,1721356239.555 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-19T02:30:39.622Z,1721356239.622 [logger ThreadHandler](INFO): Thread cancelled.