2023-06-05T22:29:45.682Z,1686004185.682 [Supervisor](DEBUG): Initializing supervisor. 2023-06-05T22:29:45.687Z,1686004185.687 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2023-06-05T22:29:45.687Z,1686004185.687 [SyncHandler](INFO): Protected caller Thread ID is 838 2023-06-05T22:29:45.688Z,1686004185.688 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2023-06-05T22:29:45.689Z,1686004185.689 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2023-06-05T22:29:45.689Z,1686004185.689 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 839 2023-06-05T22:29:45.693Z,1686004185.693 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2023-06-05T22:29:45.710Z,1686004185.710 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2023-06-05T22:29:45.711Z,1686004185.711 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2023-06-05T22:29:45.712Z,1686004185.712 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 840 2023-06-05T22:29:45.716Z,1686004185.716 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2023-06-05T22:29:45.717Z,1686004185.717 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2023-06-05T22:29:45.717Z,1686004185.717 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 841 2023-06-05T22:29:45.719Z,1686004185.719 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2023-06-05T22:29:45.721Z,1686004185.721 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2023-06-05T22:29:45.721Z,1686004185.721 [logger ThreadHandler](INFO): Protected caller Thread ID is 842 2023-06-05T22:29:45.725Z,1686004185.725 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2023-06-05T22:29:45.725Z,1686004185.725 [Supervisor](INFO): Looking for Config files in directory: Config/ 2023-06-05T22:29:45.729Z,1686004185.729 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2023-06-05T22:29:46.286Z,1686004186.286 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2023-06-05T22:29:46.288Z,1686004186.288 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2023-06-05T22:29:46.496Z,1686004186.496 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2023-06-05T22:29:46.498Z,1686004186.498 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2023-06-05T22:29:46.579Z,1686004186.579 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2023-06-05T22:29:46.792Z,1686004186.792 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2023-06-05T22:29:46.794Z,1686004186.794 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2023-06-05T22:29:46.875Z,1686004186.875 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2023-06-05T22:29:46.971Z,1686004186.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2023-06-05T22:29:46.972Z,1686004186.972 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2023-06-05T22:29:47.316Z,1686004187.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2023-06-05T22:29:47.317Z,1686004187.317 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2023-06-05T22:29:47.450Z,1686004187.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2023-06-05T22:29:47.452Z,1686004187.452 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2023-06-05T22:29:47.928Z,1686004187.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2023-06-05T22:29:47.930Z,1686004187.930 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2023-06-05T22:29:48.109Z,1686004188.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2023-06-05T22:29:48.111Z,1686004188.111 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2023-06-05T22:29:48.500Z,1686004188.500 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2023-06-05T22:29:48.502Z,1686004188.502 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2023-06-05T22:29:48.788Z,1686004188.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2023-06-05T22:29:48.790Z,1686004188.790 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2023-06-05T22:29:48.898Z,1686004188.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2023-06-05T22:29:48.899Z,1686004188.899 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2023-06-05T22:29:50.377Z,1686004190.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2023-06-05T22:29:50.378Z,1686004190.378 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2023-06-05T22:29:50.701Z,1686004190.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2023-06-05T22:29:50.702Z,1686004190.702 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2023-06-05T22:29:50.921Z,1686004190.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2023-06-05T22:29:50.923Z,1686004190.923 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2023-06-05T22:29:50.926Z,1686004190.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2023-06-05T22:29:51.048Z,1686004191.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2023-06-05T22:29:51.131Z,1686004191.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2023-06-05T22:29:51.268Z,1686004191.268 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2023-06-05T22:29:51.355Z,1686004191.355 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2023-06-05T22:29:51.620Z,1686004191.620 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2023-06-05T22:29:51.621Z,1686004191.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2023-06-05T22:29:51.722Z,1686004191.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2023-06-05T22:29:51.827Z,1686004191.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2023-06-05T22:29:51.937Z,1686004191.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2023-06-05T22:29:52.081Z,1686004192.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2023-06-05T22:29:52.178Z,1686004192.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2023-06-05T22:29:52.273Z,1686004192.273 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2023-06-05T22:29:52.273Z,1686004192.273 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2023-06-05T22:29:52.289Z,1686004192.289 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2023-06-05T22:29:52.305Z,1686004192.305 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2023-06-05T22:29:52.307Z,1686004192.307 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2023-06-05T22:29:52.432Z,1686004192.432 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2023-06-05T22:29:52.433Z,1686004192.433 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2023-06-05T22:29:52.500Z,1686004192.500 [VerticalControl](DEBUG): Construct VerticalControl. 2023-06-05T22:29:52.560Z,1686004192.560 [VerticalControl] Loaded 2023-06-05T22:29:52.560Z,1686004192.560 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2023-06-05T22:29:52.563Z,1686004192.563 [HorizontalControl](DEBUG): Construct HorizontalControl. 2023-06-05T22:29:52.618Z,1686004192.618 [HorizontalControl] Loaded 2023-06-05T22:29:52.618Z,1686004192.618 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2023-06-05T22:29:52.620Z,1686004192.620 [SpeedControl](DEBUG): Construct SpeedControl. 2023-06-05T22:29:52.624Z,1686004192.624 [SpeedControl] Loaded 2023-06-05T22:29:52.624Z,1686004192.624 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2023-06-05T22:29:52.626Z,1686004192.626 [LoopControl](DEBUG): Construct LoopControl. 2023-06-05T22:29:52.627Z,1686004192.627 [LoopControl] Loaded 2023-06-05T22:29:52.627Z,1686004192.627 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2023-06-05T22:29:52.628Z,1686004192.628 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2023-06-05T22:29:52.628Z,1686004192.628 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2023-06-05T22:29:52.811Z,1686004192.811 [BuoyancyServo] Loaded 2023-06-05T22:29:52.812Z,1686004192.812 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2023-06-05T22:29:52.831Z,1686004192.831 [ElevatorServo] Loaded 2023-06-05T22:29:52.832Z,1686004192.832 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2023-06-05T22:29:52.851Z,1686004192.851 [MassServo] Loaded 2023-06-05T22:29:52.851Z,1686004192.851 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2023-06-05T22:29:52.869Z,1686004192.869 [RudderServo] Loaded 2023-06-05T22:29:52.869Z,1686004192.869 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2023-06-05T22:29:52.884Z,1686004192.884 [ThrusterHE] Loaded 2023-06-05T22:29:52.884Z,1686004192.884 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2023-06-05T22:29:52.885Z,1686004192.885 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2023-06-05T22:29:52.885Z,1686004192.885 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2023-06-05T22:29:52.960Z,1686004192.960 [DepthRateCalculator] Loaded 2023-06-05T22:29:52.960Z,1686004192.960 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2023-06-05T22:29:52.965Z,1686004192.965 [PitchRateCalculator] Loaded 2023-06-05T22:29:52.965Z,1686004192.965 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2023-06-05T22:29:52.975Z,1686004192.975 [SpeedCalculator] Loaded 2023-06-05T22:29:52.975Z,1686004192.975 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2023-06-05T22:29:52.979Z,1686004192.979 [YawRateCalculator] Loaded 2023-06-05T22:29:52.980Z,1686004192.980 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2023-06-05T22:29:52.998Z,1686004192.998 [ElevatorOffsetCalculator] Loaded 2023-06-05T22:29:52.998Z,1686004192.998 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2023-06-05T22:29:52.999Z,1686004192.999 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2023-06-05T22:29:52.999Z,1686004192.999 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2023-06-05T22:29:53.137Z,1686004193.137 [DeadReckonUsingMultipleVelocitySources] Loaded 2023-06-05T22:29:53.138Z,1686004193.138 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2023-06-05T22:29:53.151Z,1686004193.151 [NavChart] Loaded 2023-06-05T22:29:53.152Z,1686004193.152 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2023-06-05T22:29:53.157Z,1686004193.157 [UniversalFixResidualReporter] Loaded 2023-06-05T22:29:53.157Z,1686004193.157 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2023-06-05T22:29:53.158Z,1686004193.158 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2023-06-05T22:29:53.159Z,1686004193.159 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2023-06-05T22:29:53.897Z,1686004193.897 [AHRS_M2] Loaded 2023-06-05T22:29:53.898Z,1686004193.898 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2023-06-05T22:29:54.147Z,1686004194.147 [BackseatComponent] Loaded 2023-06-05T22:29:54.147Z,1686004194.147 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2023-06-05T22:29:54.148Z,1686004194.148 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408E54E0 2023-06-05T22:29:54.149Z,1686004194.149 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 925 2023-06-05T22:29:54.151Z,1686004194.151 [LcmUniversalReporter] Loaded 2023-06-05T22:29:54.151Z,1686004194.151 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2023-06-05T22:29:54.158Z,1686004194.158 [BioacousticsDataBridge] Loaded 2023-06-05T22:29:54.158Z,1686004194.158 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread. 2023-06-05T22:29:54.986Z,1686004194.986 [BPC1] Loaded 2023-06-05T22:29:54.986Z,1686004194.986 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2023-06-05T22:29:55.129Z,1686004195.129 [DataOverHttps] Loaded 2023-06-05T22:29:55.129Z,1686004195.129 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2023-06-05T22:29:55.130Z,1686004195.130 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409154E0 2023-06-05T22:29:55.131Z,1686004195.131 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 2023-06-05T22:29:55.152Z,1686004195.152 [Depth_Keller] Loaded 2023-06-05T22:29:55.152Z,1686004195.152 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2023-06-05T22:29:55.157Z,1686004195.157 [DropWeight] Loaded 2023-06-05T22:29:55.157Z,1686004195.157 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2023-06-05T22:29:55.220Z,1686004195.220 [NAL9602] Loaded 2023-06-05T22:29:55.221Z,1686004195.221 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2023-06-05T22:29:55.250Z,1686004195.250 [Onboard] Loaded 2023-06-05T22:29:55.250Z,1686004195.250 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2023-06-05T22:29:55.251Z,1686004195.251 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409454E0 2023-06-05T22:29:55.252Z,1686004195.252 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 2023-06-05T22:29:55.288Z,1686004195.288 [Power24vConverter] Loaded 2023-06-05T22:29:55.288Z,1686004195.288 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2023-06-05T22:29:55.310Z,1686004195.310 [Radio_Surface] Loaded 2023-06-05T22:29:55.310Z,1686004195.310 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2023-06-05T22:29:55.320Z,1686004195.320 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409754E0 2023-06-05T22:29:55.320Z,1686004195.320 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 929 2023-06-05T22:29:55.337Z,1686004195.337 [Sonardyne_Nano] Loaded 2023-06-05T22:29:55.338Z,1686004195.338 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2023-06-05T22:29:55.338Z,1686004195.338 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2023-06-05T22:29:55.339Z,1686004195.339 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2023-06-05T22:29:56.014Z,1686004196.014 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2023-06-05T22:29:56.015Z,1686004196.015 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2023-06-05T22:29:56.339Z,1686004196.339 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2023-06-05T22:29:56.340Z,1686004196.340 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2023-06-05T22:29:56.383Z,1686004196.383 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2023-06-05T22:29:56.384Z,1686004196.384 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2023-06-05T22:29:56.597Z,1686004196.597 [CTD_Seabird] Loaded 2023-06-05T22:29:56.597Z,1686004196.597 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2023-06-05T22:29:56.598Z,1686004196.598 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA94E0 2023-06-05T22:29:56.599Z,1686004196.599 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 930 2023-06-05T22:29:56.619Z,1686004196.619 [PAR_Licor] Loaded 2023-06-05T22:29:56.619Z,1686004196.619 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2023-06-05T22:29:56.650Z,1686004196.650 [WetLabsBB2FL] Loaded 2023-06-05T22:29:56.650Z,1686004196.650 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2023-06-05T22:29:56.651Z,1686004196.651 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD94E0 2023-06-05T22:29:56.652Z,1686004196.652 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 931 2023-06-05T22:29:56.653Z,1686004196.653 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2023-06-05T22:29:56.653Z,1686004196.653 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2023-06-05T22:29:57.011Z,1686004197.011 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2023-06-05T22:29:57.012Z,1686004197.012 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2023-06-05T22:29:57.182Z,1686004197.182 [SBIT](DEBUG): Construct Startup Built In Test. 2023-06-05T22:29:57.191Z,1686004197.191 [SBIT] Loaded 2023-06-05T22:29:57.192Z,1686004197.192 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2023-06-05T22:29:57.194Z,1686004197.194 [IBIT](DEBUG): Construct Initiated Built In Test. 2023-06-05T22:29:57.208Z,1686004197.208 [IBIT] Loaded 2023-06-05T22:29:57.208Z,1686004197.208 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2023-06-05T22:29:57.214Z,1686004197.214 [CBIT](DEBUG): Construct Continuous Built In Test. 2023-06-05T22:29:57.319Z,1686004197.319 [CBIT] Loaded 2023-06-05T22:29:57.319Z,1686004197.319 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2023-06-05T22:29:57.319Z,1686004197.319 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2023-06-05T22:29:57.325Z,1686004197.325 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2023-06-05T22:29:57.328Z,1686004197.328 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2023-06-05T22:29:57.340Z,1686004197.340 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2023-06-05T22:29:57.341Z,1686004197.341 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC44E0 2023-06-05T22:29:57.341Z,1686004197.341 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 932 2023-06-05T22:29:57.346Z,1686004197.346 [Supervisor](INFO): Main Thread ID is 831 2023-06-05T22:29:57.346Z,1686004197.346 [Supervisor](DEBUG): Running supervisor. 2023-06-05T22:29:57.347Z,1686004197.347 [CommandExec ThreadHandler](INFO): Handler Thread ID is 933 2023-06-05T22:29:57.347Z,1686004197.347 [CommandExec](INFO): Initializing the command executive. 2023-06-05T22:29:57.348Z,1686004197.348 [CommandLine ThreadHandler](INFO): Handler Thread ID is 934 2023-06-05T22:29:57.351Z,1686004197.351 [controlThread ThreadHandler](INFO): Handler Thread ID is 935 2023-06-05T22:29:57.351Z,1686004197.351 [controlThread](DEBUG): Initializing ControlThread 2023-06-05T22:29:57.352Z,1686004197.352 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2023-06-05T22:29:57.354Z,1686004197.354 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2023-06-05T22:29:57.355Z,1686004197.355 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2023-06-05T22:29:57.355Z,1686004197.355 [LoopControl](DEBUG): Initialize LoopControlComponent. 2023-06-05T22:29:57.357Z,1686004197.357 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2023-06-05T22:29:57.357Z,1686004197.357 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2023-06-05T22:29:57.358Z,1686004197.358 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2023-06-05T22:29:57.358Z,1686004197.358 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2023-06-05T22:29:57.358Z,1686004197.358 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2023-06-05T22:29:57.359Z,1686004197.359 [NavChart](DEBUG): Initialize NavChart Navigation. 2023-06-05T22:29:57.360Z,1686004197.360 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2023-06-05T22:29:57.368Z,1686004197.368 [SBIT](INFO): Initialize SBIT Component. 2023-06-05T22:29:57.369Z,1686004197.369 [SBIT](IMPORTANT): git: 2023-06-05C 2023-06-05T22:29:57.369Z,1686004197.369 [SBIT](INFO): git hash: efc15f6be040f885929981e6c646f5e296303a53 2023-06-05T22:29:57.369Z,1686004197.369 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2023-06-05T22:29:57.370Z,1686004197.370 [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 2023-06-05T22:29:57.371Z,1686004197.371 [SBIT](INFO): Beginning SBIT in 120.000000 seconds. 2023-06-05T22:29:57.372Z,1686004197.372 [IBIT](INFO): Initialize IBIT Component. 2023-06-05T22:29:57.373Z,1686004197.373 [CBIT](DEBUG): Initialize CBIT Component. 2023-06-05T22:29:57.374Z,1686004197.374 [logger ThreadHandler](INFO): Handler Thread ID is 936 2023-06-05T22:29:57.384Z,1686004197.384 [CBIT](DEBUG): Initialized mux pins. 2023-06-05T22:29:57.384Z,1686004197.384 [CBIT](DEBUG): Initializing the watchdog timer. 2023-06-05T22:29:57.392Z,1686004197.392 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 937 2023-06-05T22:29:57.404Z,1686004197.404 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938 2023-06-05T22:29:57.405Z,1686004197.405 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2023-06-05T22:29:57.408Z,1686004197.408 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2023-06-05T22:29:57.408Z,1686004197.408 [CBIT](DEBUG): Initializing heartbeat. 2023-06-05T22:29:57.416Z,1686004197.416 [Onboard ThreadHandler](INFO): Handler Thread ID is 939 2023-06-05T22:29:57.434Z,1686004197.434 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940 2023-06-05T22:29:57.452Z,1686004197.452 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941 2023-06-05T22:29:57.453Z,1686004197.453 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2023-06-05T22:29:57.457Z,1686004197.457 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943 2023-06-05T22:29:57.460Z,1686004197.460 [WetLabsBB2FL](INFO): Powering up 2023-06-05T22:29:57.462Z,1686004197.462 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945 2023-06-05T22:29:57.469Z,1686004197.469 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2023-06-05T22:29:57.470Z,1686004197.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2023-06-05T22:29:57.470Z,1686004197.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2023-06-05T22:29:57.470Z,1686004197.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2023-06-05T22:29:57.470Z,1686004197.470 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2023-06-05T22:29:57.470Z,1686004197.470 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2023-06-05T22:29:57.471Z,1686004197.471 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2023-06-05T22:29:57.471Z,1686004197.471 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2023-06-05T22:29:57.480Z,1686004197.480 [CBIT](DEBUG): Deactivating GF circuits. 2023-06-05T22:29:57.480Z,1686004197.480 [CBIT](DEBUG): Deactivating emergency mode. 2023-06-05T22:29:57.520Z,1686004197.520 [CBIT](DEBUG): Backplane powered. 2023-06-05T22:29:57.521Z,1686004197.521 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 2023-06-05T22:29:57.549Z,1686004197.549 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-05T22:29:57.597Z,1686004197.597 [MissionManager](DEBUG): 2023-06-05T22:29:57.598Z,1686004197.598 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 2023-06-05T22:29:57.690Z,1686004197.690 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2023-06-05T22:29:57.691Z,1686004197.691 [Default:A.Wait](DEBUG): Construct Wait. 2023-06-05T22:29:57.694Z,1686004197.694 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-05T22:29:57.749Z,1686004197.749 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-05T22:29:57.752Z,1686004197.752 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-05T22:29:57.771Z,1686004197.771 [Default:E.Execute](DEBUG): Construct Execute. 2023-06-05T22:29:57.787Z,1686004197.787 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2023-06-05T22:29:57.791Z,1686004197.791 [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, 2023-06-05T22:29:57.800Z,1686004197.800 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2023-06-05T22:29:57.840Z,1686004197.840 [Radio_Surface](INFO): Powering up 2023-06-05T22:29:57.993Z,1686004197.993 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1926.023804 decibar, 0.000000 m 2023-06-05T22:29:57.994Z,1686004197.994 [Power24vConverter](INFO): Powering up. 2023-06-05T22:29:57.995Z,1686004197.995 [Sonardyne_Nano](INFO): Initializing. 2023-06-05T22:29:58.040Z,1686004198.040 [DepthRateCalculator](ERROR): Depth measurement is not active 2023-06-05T22:29:58.062Z,1686004198.062 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2023-06-05T22:29:58.068Z,1686004198.068 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2023-06-05T22:29:58.069Z,1686004198.069 [ElevatorServo](DEBUG): Initializing EZServoServo. 2023-06-05T22:29:58.076Z,1686004198.076 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2023-06-05T22:29:58.077Z,1686004198.077 [MassServo](DEBUG): Initializing EZServoServo. 2023-06-05T22:29:58.084Z,1686004198.084 [MassServo](DEBUG): Initializing MassServo. 2023-06-05T22:29:58.085Z,1686004198.085 [RudderServo](DEBUG): Initializing EZServoServo. 2023-06-05T22:29:58.092Z,1686004198.092 [RudderServo](DEBUG): Initializing RudderServo. 2023-06-05T22:29:58.093Z,1686004198.093 [ThrusterHE](DEBUG): Initializing EZServoServo. 2023-06-05T22:29:58.100Z,1686004198.100 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2023-06-05T22:29:58.245Z,1686004198.245 [Sonardyne_Nano](INFO): Starting 2023-06-05T22:29:58.610Z,1686004198.610 [Sonardyne_Nano](INFO): Starting 2023-06-05T22:29:59.019Z,1686004199.019 [Sonardyne_Nano](INFO): Starting 2023-06-05T22:29:59.418Z,1686004199.418 [Sonardyne_Nano](INFO): Starting 2023-06-05T22:29:59.569Z,1686004199.569 [WetLabsBB2FL](INFO): Powering down 2023-06-05T22:29:59.821Z,1686004199.821 [Sonardyne_Nano](INFO): Starting 2023-06-05T22:30:00.226Z,1686004200.226 [Sonardyne_Nano](INFO): Received response:>+++? 2023-06-05T22:30:00.226Z,1686004200.226 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2023-06-05T22:30:00.240Z,1686004200.240 [Sonardyne_Nano](INFO): Command indicated, sending CKHW 2023-06-05T22:30:00.630Z,1686004200.630 [Sonardyne_Nano](INFO): Command indicated, sending CKHW 2023-06-05T22:30:01.106Z,1686004201.106 [Sonardyne_Nano](INFO): Received response:>CKHW:PASS 2023-06-05T22:30:01.106Z,1686004201.106 [Sonardyne_Nano](INFO): Received self-test result 2023-06-05T22:30:01.107Z,1686004201.107 [Sonardyne_Nano](INFO): Command indicated, sending FS 2023-06-05T22:30:01.465Z,1686004201.465 [Sonardyne_Nano](INFO): Received response:>FS:2201,U006F43,FL0010,FV5.11.00.07,PV2.03,TDR;MF;OMNI;184;175;169,PR5000;SG;AC0.700,T-40;110;AC2.00,INC-90.00;+90.00;AC1.00 2023-06-05T22:30:01.465Z,1686004201.465 [Sonardyne_Nano](INFO): Read UUID: %x28483 2023-06-05T22:30:01.465Z,1686004201.465 [Sonardyne_Nano](INFO): Command indicated, sending VS 2023-06-05T22:30:01.867Z,1686004201.867 [Sonardyne_Nano](INFO): Received response:>VS:2201,HPR45,EXT,TILT,BT1;LI_ION;VLT4.0;IDC-1.5;CAP3.4/77;T21.6 2023-06-05T22:30:03.677Z,1686004203.677 [ThrusterHE](ERROR): Zero Speed Commanded. 2023-06-05T22:30:04.485Z,1686004204.485 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2023-06-05T22:30:25.283Z,1686004225.283 [NAL9602](INFO): Powering up NAL9602 2023-06-05T22:30:36.194Z,1686004236.194 [NAL9602](INFO): NAL9602 initialized 2023-06-05T22:31:32.484Z,1686004292.484 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2023-06-05T22:31:32.485Z,1686004292.485 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2023-06-05T22:31:57.835Z,1686004317.835 [SBIT](IMPORTANT): Beginning Startup BIT 2023-06-05T22:31:57.843Z,1686004317.843 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-05T22:32:09.130Z,1686004329.130 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005009 CHAN A1 (24V): -0.005938 CHAN A2 (12V): -0.003939 CHAN A3 (5V): -0.003120 CHAN B0 (3.3V): -0.000863 CHAN B1 (3.15aV): -0.001935 CHAN B2 (3.15bV): -0.000840 CHAN B3 (GND): -0.000889 OPEN: 0.003444 Full Scale: +/- 1 mA 2023-06-05T22:32:26.319Z,1686004346.319 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005059 2023-06-05T22:32:52.006Z,1686004372.006 [SBIT](IMPORTANT): SBIT PASSED 2023-06-05T22:32:52.006Z,1686004372.006 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): BioacousticsDataBridge.loadAtStartup=1 bool; 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=10 minute; 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.azimuth_to_contact_vehicle_frame 10.000000 angular_degree; 2023-06-05T22:32:52.007Z,1686004372.007 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 10.000000 meter; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): Express linearApproximation platform_roll_angle 5.000000 degree; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=184.896478 cubic_centimeter; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): VerticalControl.elevDeadband=0.1 angular_degree; 2023-06-05T22:32:52.008Z,1686004372.008 [SBIT](IMPORTANT): VerticalControl.massDefault=2.5 centimeter; 2023-06-05T22:32:52.416Z,1686004372.416 [MissionManager](IMPORTANT): Started mission Startup 2023-06-05T22:32:52.417Z,1686004372.417 [Startup] Running Loop=1 2023-06-05T22:32:52.417Z,1686004372.417 [Startup](DEBUG): Aggregate::initialize Startup 2023-06-05T22:32:52.417Z,1686004372.417 [Startup:A.GoToSurface] Running Loop=1 2023-06-05T22:32:52.417Z,1686004372.417 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-05T22:32:52.418Z,1686004372.418 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-05T22:32:52.418Z,1686004372.418 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-05T22:32:52.418Z,1686004372.418 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-05T22:32:52.419Z,1686004372.419 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-05T22:32:52.419Z,1686004372.419 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-05T22:32:52.421Z,1686004372.421 [Startup:StartupSatComms] Running Loop=1 2023-06-05T22:32:52.421Z,1686004372.421 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2023-06-05T22:32:52.421Z,1686004372.421 [Startup:StartupSatComms:A] Running Loop=1 2023-06-05T22:32:52.792Z,1686004372.792 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2023-06-05T22:32:57.626Z,1686004377.626 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2023-06-05T22:32:57.626Z,1686004377.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:32:57.673Z,1686004377.673 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:32:58.049Z,1686004378.049 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:32:58.049Z,1686004378.049 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2023-06-05T22:33:06.534Z,1686004386.534 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:13.337Z,1686004393.337 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:13.343Z,1686004393.343 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:28.285Z,1686004408.285 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:28.706Z,1686004408.706 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:43.638Z,1686004423.638 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:43.644Z,1686004423.644 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:52.562Z,1686004432.562 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-05T22:32:52.4Z 2023-06-05T22:33:52.562Z,1686004432.562 [Startup:StartupSatComms:A] Stopped 2023-06-05T22:33:52.562Z,1686004432.562 [Startup:StartupSatComms:B] Running Loop=1 2023-06-05T22:33:52.972Z,1686004432.972 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-05T22:33:58.585Z,1686004438.585 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:58.591Z,1686004438.591 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:33:59.471Z,1686004439.471 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230605T220612/Courier0010.lzma 2023-06-05T22:34:00.474Z,1686004440.474 [DataOverHttps](INFO): Moved sent file to Logs/20230605T220612/Courier0010.lzma.bak 2023-06-05T22:34:00.474Z,1686004440.474 [DataOverHttps](INFO): SBD MOMSN=18398039 2023-06-05T22:34:13.533Z,1686004453.533 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:13.539Z,1686004453.539 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:16.043Z,1686004456.043 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230605T222945/Courier0000.lzma 2023-06-05T22:34:17.046Z,1686004457.046 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0000.lzma.bak 2023-06-05T22:34:17.046Z,1686004457.046 [DataOverHttps](INFO): SBD MOMSN=18398044 2023-06-05T22:34:28.552Z,1686004468.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:28.895Z,1686004468.895 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:32.563Z,1686004472.563 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20230605T220612/Express0011.lzma 2023-06-05T22:34:33.566Z,1686004473.566 [DataOverHttps](INFO): Moved sent file to Logs/20230605T220612/Express0011.lzma.bak 2023-06-05T22:34:33.566Z,1686004473.566 [DataOverHttps](INFO): SBD MOMSN=18398047 2023-06-05T22:34:43.429Z,1686004483.429 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:44.238Z,1686004484.238 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:49.137Z,1686004489.137 [DataOverHttps](INFO): Sending 1055 bytes from file Logs/20230605T222945/Express0001.lzma 2023-06-05T22:34:50.138Z,1686004490.138 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0001.lzma.bak 2023-06-05T22:34:50.138Z,1686004490.138 [DataOverHttps](INFO): SBD MOMSN=18398049 2023-06-05T22:34:51.538Z,1686004491.538 [Startup:StartupSatComms:B] Stopped 2023-06-05T22:34:51.538Z,1686004491.538 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2023-06-05T22:34:51.538Z,1686004491.538 [Startup:StartupSatComms] Stopped 2023-06-05T22:34:51.538Z,1686004491.538 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2023-06-05T22:34:51.539Z,1686004491.539 [Startup](INFO): Completed Startup 2023-06-05T22:34:51.539Z,1686004491.539 [MissionManager](INFO): Startup is completed. 2023-06-05T22:34:51.539Z,1686004491.539 [MissionManager](INFO): Uninitializing Mission Startup 2023-06-05T22:34:51.539Z,1686004491.539 [Startup] Stopped 2023-06-05T22:34:51.540Z,1686004491.540 [Startup](DEBUG): Aggregate::uninitialize Startup 2023-06-05T22:34:51.540Z,1686004491.540 [Startup:A.GoToSurface] Stopped 2023-06-05T22:34:51.540Z,1686004491.540 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-05T22:34:51.929Z,1686004491.929 [MissionManager](IMPORTANT): Started mission Default 2023-06-05T22:34:51.930Z,1686004491.930 [Default] Running Loop=1 2023-06-05T22:34:51.930Z,1686004491.930 [Default](DEBUG): Aggregate::initialize Default 2023-06-05T22:34:51.930Z,1686004491.930 [Default:B.GoToSurface] Running Loop=1 2023-06-05T22:34:51.930Z,1686004491.930 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-05T22:34:51.930Z,1686004491.930 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-05T22:34:51.931Z,1686004491.931 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-05T22:34:51.931Z,1686004491.931 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-05T22:34:51.931Z,1686004491.931 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-05T22:34:51.931Z,1686004491.931 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-05T22:34:51.944Z,1686004491.944 [Default:A.Wait] Running Loop=1 2023-06-05T22:34:51.944Z,1686004491.944 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-06-05T22:34:58.831Z,1686004498.831 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:34:59.189Z,1686004499.189 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:35:05.276Z,1686004505.276 [Default:A.Wait](INFO): Done Waiting. 2023-06-05T22:35:05.276Z,1686004505.276 [Default:A.Wait] Stopped 2023-06-05T22:35:05.277Z,1686004505.277 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T22:35:05.698Z,1686004505.698 [Default:CheckIn] Running Loop=1 2023-06-05T22:35:05.699Z,1686004505.699 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T22:35:05.699Z,1686004505.699 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T22:35:06.091Z,1686004506.091 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-06-05T22:35:14.187Z,1686004514.187 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:35:14.540Z,1686004514.540 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:35:29.894Z,1686004529.894 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7). 2023-06-05T22:35:30.308Z,1686004530.308 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2023-06-05T22:35:38.794Z,1686004538.794 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2023-06-05T22:35:58.611Z,1686004558.611 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2023-06-05T22:35:58.611Z,1686004558.611 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:35:58.621Z,1686004558.621 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:35:59.023Z,1686004559.023 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:35:59.023Z,1686004559.023 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2023-06-05T22:37:22.088Z,1686004642.088 [CommandExec](IMPORTANT): got command run ./Missions/Maintenance/run_backseat_on_surface.xml 2023-06-05T22:37:22.089Z,1686004642.089 [MissionManager](INFO): Loading Mission from file: ./Missions/Maintenance/run_backseat_on_surface.xml 2023-06-05T22:37:22.129Z,1686004642.129 [MissionManager](INFO): DefineArg run_backseat_on_surface.ElapsedSinceMissionStarted = 0.000000 min 2023-06-05T22:37:22.129Z,1686004642.129 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/Science.xml 2023-06-05T22:37:22.455Z,1686004642.455 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectChlActive = 0 bool 2023-06-05T22:37:22.458Z,1686004642.458 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.TimeWindowPeakReport = nan min 2023-06-05T22:37:22.461Z,1686004642.461 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestChlPeakReportActive = 0 bool 2023-06-05T22:37:22.484Z,1686004642.484 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestSaltPeakReportActive = 0 bool 2023-06-05T22:37:22.487Z,1686004642.487 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.HighestOilPeakReportActive = 0 bool 2023-06-05T22:37:22.499Z,1686004642.499 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PatchTracking = 0 bool 2023-06-05T22:37:22.502Z,1686004642.502 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DetectTrough = 0 bool 2023-06-05T22:37:22.506Z,1686004642.506 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.FilterWidthHorizontal = 3.000000 count 2023-06-05T22:37:22.510Z,1686004642.510 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesSlidingwindow = 100.000000 count 2023-06-05T22:37:22.514Z,1686004642.514 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.OffPeakFractionHorizontal = 80.000000 % 2023-06-05T22:37:22.520Z,1686004642.520 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectNO3Active = 0 bool 2023-06-05T22:37:22.523Z,1686004642.523 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectOilActive = 0 bool 2023-06-05T22:37:22.527Z,1686004642.527 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectFDOMActive = 0 bool 2023-06-05T22:37:22.542Z,1686004642.542 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDetectSalinityActive = 0 bool 2023-06-05T22:37:22.548Z,1686004642.548 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.UpwardDerivativeOfTemperatureActive = 0 bool 2023-06-05T22:37:22.556Z,1686004642.556 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledAanderaaO2 = 0 bool 2023-06-05T22:37:22.559Z,1686004642.559 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledNeilBrown = 0 bool 2023-06-05T22:37:22.570Z,1686004642.570 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledSeabird = 1 bool 2023-06-05T22:37:22.578Z,1686004642.578 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsBB2FL = 1 bool 2023-06-05T22:37:22.583Z,1686004642.583 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsSeaOWL_UV_A = 0 bool 2023-06-05T22:37:22.630Z,1686004642.630 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.EnabledWetLabsUBAT = 0 bool 2023-06-05T22:37:22.648Z,1686004642.648 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.LowPassWindowLength = 20.000000 count 2023-06-05T22:37:22.651Z,1686004642.651 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.MedianFilterLen = 5.000000 count 2023-06-05T22:37:22.666Z,1686004642.666 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakShallowBound = nan m 2023-06-05T22:37:22.674Z,1686004642.674 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.PeakDeepBound = nan m 2023-06-05T22:37:22.689Z,1686004642.689 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.DepChangeThreshForAttitudeFlip = 2.000000 m 2023-06-05T22:37:22.696Z,1686004642.696 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlThreshTriggerActive = 0 bool 2023-06-05T22:37:22.707Z,1686004642.707 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlLowerThresh = nan ug/l 2023-06-05T22:37:22.710Z,1686004642.710 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.ChlUpperThresh = nan ug/l 2023-06-05T22:37:22.726Z,1686004642.726 [MissionManager](INFO): DefineArg run_backseat_on_surface:Science.NumProfilesThresh = 5.000000 count 2023-06-05T22:37:22.730Z,1686004642.730 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChl = nan ug/l 2023-06-05T22:37:22.741Z,1686004642.741 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlDepth = nan m 2023-06-05T22:37:22.756Z,1686004642.756 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlTemperature = nan degC 2023-06-05T22:37:22.759Z,1686004642.759 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLatitude = nan arcdeg 2023-06-05T22:37:22.771Z,1686004642.771 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakChlLongitude = nan arcdeg 2023-06-05T22:37:22.778Z,1686004642.778 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3 = nan umol/l 2023-06-05T22:37:22.785Z,1686004642.785 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Depth = nan m 2023-06-05T22:37:22.824Z,1686004642.824 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Latitude = nan arcdeg 2023-06-05T22:37:22.827Z,1686004642.827 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakNO3Longitude = nan arcdeg 2023-06-05T22:37:22.835Z,1686004642.835 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOil = nan kg/m3 2023-06-05T22:37:22.846Z,1686004642.846 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilDepth = nan m 2023-06-05T22:37:22.849Z,1686004642.849 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLatitude = nan arcdeg 2023-06-05T22:37:22.860Z,1686004642.860 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakOilLongitude = nan arcdeg 2023-06-05T22:37:22.872Z,1686004642.872 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOM = nan ppb 2023-06-05T22:37:22.878Z,1686004642.878 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMDepth = nan m 2023-06-05T22:37:22.883Z,1686004642.883 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMTemperature = nan degC 2023-06-05T22:37:22.891Z,1686004642.891 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLatitude = nan arcdeg 2023-06-05T22:37:22.898Z,1686004642.898 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakFDOMLongitude = nan arcdeg 2023-06-05T22:37:22.917Z,1686004642.917 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchFDOMDistance = nan m 2023-06-05T22:37:22.928Z,1686004642.928 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSalt = nan psu 2023-06-05T22:37:22.931Z,1686004642.931 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltDepth = nan m 2023-06-05T22:37:22.939Z,1686004642.939 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltTemperature = nan degC 2023-06-05T22:37:22.942Z,1686004642.942 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLatitude = nan arcdeg 2023-06-05T22:37:22.953Z,1686004642.953 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PeakSaltLongitude = nan arcdeg 2023-06-05T22:37:22.964Z,1686004642.964 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChl = nan ug/l 2023-06-05T22:37:22.967Z,1686004642.967 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDepth = nan m 2023-06-05T22:37:22.974Z,1686004642.974 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLatitude = nan arcdeg 2023-06-05T22:37:22.978Z,1686004642.978 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlLongitude = nan arcdeg 2023-06-05T22:37:22.997Z,1686004642.997 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchChlDistance = nan m 2023-06-05T22:37:23.032Z,1686004643.032 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSalt = nan psu 2023-06-05T22:37:23.035Z,1686004643.035 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDepth = nan m 2023-06-05T22:37:23.046Z,1686004643.046 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLatitude = nan arcdeg 2023-06-05T22:37:23.053Z,1686004643.053 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltLongitude = nan arcdeg 2023-06-05T22:37:23.069Z,1686004643.069 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchSaltDistance = nan m 2023-06-05T22:37:23.076Z,1686004643.076 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOil = nan psu 2023-06-05T22:37:23.079Z,1686004643.079 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDepth = nan m 2023-06-05T22:37:23.086Z,1686004643.086 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLatitude = nan arcdeg 2023-06-05T22:37:23.093Z,1686004643.093 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilLongitude = nan arcdeg 2023-06-05T22:37:23.097Z,1686004643.097 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.PatchOilDistance = nan m 2023-06-05T22:37:23.116Z,1686004643.116 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.TriggeredOnChl = 0 bool 2023-06-05T22:37:23.119Z,1686004643.119 [MissionManager](INFO): DefineOutput run_backseat_on_surface:Science.ChlTriggering = nan ug/l 2023-06-05T22:37:23.194Z,1686004643.194 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-06-05T22:37:23.206Z,1686004643.206 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-06-05T22:37:23.240Z,1686004643.240 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-06-05T22:37:23.283Z,1686004643.283 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-06-05T22:37:23.313Z,1686004643.313 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-06-05T22:37:23.367Z,1686004643.367 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-06-05T22:37:23.399Z,1686004643.399 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth. 2023-06-05T22:37:23.455Z,1686004643.455 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal. 2023-06-05T22:37:23.493Z,1686004643.493 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Construct ValueDetect. 2023-06-05T22:37:23.510Z,1686004643.510 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Construct BackseatDriver. 2023-06-05T22:37:23.514Z,1686004643.514 [run_backseat_on_surface:C.GoToSurface](DEBUG): Construct GoToSurface. 2023-06-05T22:37:23.556Z,1686004643.556 [run_backseat_on_surface:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2023-06-05T22:37:23.558Z,1686004643.558 [run_backseat_on_surface:CheckIn:C.Wait](DEBUG): Construct Wait. 2023-06-05T22:37:23.570Z,1686004643.570 [run_backseat_on_surface:F.Execute](DEBUG): Construct Execute. 2023-06-05T22:37:23.634Z,1686004643.634 [MissionManager](DEBUG): 0 Burn on Dropped weight due to communications timeout. 5 run_backseat_on_surface mission has been running for Restarting logs and run_backseat_on_surface mission. restart logs 2023-06-05T22:37:23.646Z,1686004643.646 [CommandExec](IMPORTANT): Running ./Missions/Maintenance/run_backseat_on_surface.xml 2023-06-05T22:37:23.842Z,1686004643.842 [Default] Stopped 2023-06-05T22:37:23.842Z,1686004643.842 [Default](DEBUG): Aggregate::uninitialize Default 2023-06-05T22:37:23.843Z,1686004643.843 [Default:B.GoToSurface] Stopped 2023-06-05T22:37:23.843Z,1686004643.843 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-05T22:37:23.843Z,1686004643.843 [Default:CheckIn] Stopped 2023-06-05T22:37:23.843Z,1686004643.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T22:37:23.843Z,1686004643.843 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T22:37:23.843Z,1686004643.843 [MissionManager](IMPORTANT): Started mission run_backseat_on_surface 2023-06-05T22:37:23.843Z,1686004643.843 [run_backseat_on_surface] Running Loop=1 2023-06-05T22:37:23.843Z,1686004643.843 [run_backseat_on_surface](DEBUG): Aggregate::initialize run_backseat_on_surface 2023-06-05T22:37:23.843Z,1686004643.843 [run_backseat_on_surface:Science] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science](DEBUG): Aggregate::initialize run_backseat_on_surface:Science 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:A] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:B] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:C] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:D] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:E] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:F] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:Read_Oil] Running Loop=1 2023-06-05T22:37:23.848Z,1686004643.848 [run_backseat_on_surface:Science:PeakDetectChl] Running Loop=1 2023-06-05T22:37:23.849Z,1686004643.849 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectChl 2023-06-05T22:37:23.849Z,1686004643.849 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1 2023-06-05T22:37:23.849Z,1686004643.849 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-06-05T22:37:23.851Z,1686004643.851 [run_backseat_on_surface:Science:HighestChlPeakReport] Running Loop=1 2023-06-05T22:37:23.851Z,1686004643.851 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestChlPeakReport 2023-06-05T22:37:23.851Z,1686004643.851 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-06-05T22:37:23.851Z,1686004643.851 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-06-05T22:37:23.856Z,1686004643.856 [run_backseat_on_surface:Science:PeakDetectNO3] Running Loop=1 2023-06-05T22:37:23.856Z,1686004643.856 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectNO3 2023-06-05T22:37:23.856Z,1686004643.856 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1 2023-06-05T22:37:23.856Z,1686004643.856 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-06-05T22:37:23.861Z,1686004643.861 [run_backseat_on_surface:Science:PeakDetectOil] Running Loop=1 2023-06-05T22:37:23.862Z,1686004643.862 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectOil 2023-06-05T22:37:23.862Z,1686004643.862 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1 2023-06-05T22:37:23.862Z,1686004643.862 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-06-05T22:37:23.864Z,1686004643.864 [run_backseat_on_surface:Science:HighestOilPeakReport] Running Loop=1 2023-06-05T22:37:23.864Z,1686004643.864 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestOilPeakReport 2023-06-05T22:37:23.864Z,1686004643.864 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-06-05T22:37:23.864Z,1686004643.864 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-06-05T22:37:23.865Z,1686004643.865 [run_backseat_on_surface:Science:PeakDetectFDOM] Running Loop=1 2023-06-05T22:37:23.865Z,1686004643.865 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectFDOM 2023-06-05T22:37:23.865Z,1686004643.865 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1 2023-06-05T22:37:23.865Z,1686004643.865 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-06-05T22:37:23.867Z,1686004643.867 [run_backseat_on_surface:Science:PeakDetectSalinity] Running Loop=1 2023-06-05T22:37:23.867Z,1686004643.867 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:PeakDetectSalinity 2023-06-05T22:37:23.867Z,1686004643.867 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Running Loop=1 2023-06-05T22:37:23.867Z,1686004643.867 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Initialize. 2023-06-05T22:37:23.881Z,1686004643.881 [run_backseat_on_surface:Science:HighestSaltPeakReport] Running Loop=1 2023-06-05T22:37:23.881Z,1686004643.881 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:HighestSaltPeakReport 2023-06-05T22:37:23.881Z,1686004643.881 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Running Loop=1 2023-06-05T22:37:23.882Z,1686004643.882 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](INFO): Initialize. 2023-06-05T22:37:23.882Z,1686004643.882 [run_backseat_on_surface:Science:ChlThreshTrigger] Running Loop=1 2023-06-05T22:37:23.882Z,1686004643.882 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:ChlThreshTrigger 2023-06-05T22:37:23.882Z,1686004643.882 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Running Loop=1 2023-06-05T22:37:23.882Z,1686004643.882 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](INFO): Initialize. 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:Science:OceanCurrent] Running Loop=1 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::initialize run_backseat_on_surface:Science:OceanCurrent 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator. 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1 2023-06-05T22:37:23.883Z,1686004643.883 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Initialize. 2023-06-05T22:37:23.924Z,1686004643.924 [BackseatComponent](INFO): Powering up 2023-06-05T22:37:23.925Z,1686004643.925 [BackseatComponent](INFO): Subscribing to LCM channels. 2023-06-05T22:37:24.017Z,1686004644.017 [run_backseat_on_surface:C.GoToSurface] Running Loop=1 2023-06-05T22:37:24.017Z,1686004644.017 [run_backseat_on_surface:C.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-05T22:37:24.018Z,1686004644.018 [run_backseat_on_surface:C.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-05T22:37:24.018Z,1686004644.018 [run_backseat_on_surface:C.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-05T22:37:24.018Z,1686004644.018 [run_backseat_on_surface:C.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-05T22:37:24.018Z,1686004644.018 [run_backseat_on_surface:C.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-05T22:37:24.019Z,1686004644.019 [run_backseat_on_surface:C.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-05T22:37:24.020Z,1686004644.020 [run_backseat_on_surface:CheckIn] Running Loop=1 2023-06-05T22:37:24.020Z,1686004644.020 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::initialize run_backseat_on_surface:CheckIn 2023-06-05T22:37:24.020Z,1686004644.020 [run_backseat_on_surface:CheckIn:Read_GPS] Running Loop=1 2023-06-05T22:37:24.020Z,1686004644.020 [run_backseat_on_surface:B.BackseatDriver] Running Loop=1 2023-06-05T22:37:24.027Z,1686004644.027 [run_backseat_on_surface:Science] Running Loop=1 2023-06-05T22:37:24.032Z,1686004644.032 [run_backseat_on_surface:Science:ChlThreshTrigger] Stopped 2023-06-05T22:37:24.032Z,1686004644.032 [run_backseat_on_surface:Science:ChlThreshTrigger](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:ChlThreshTrigger 2023-06-05T22:37:24.032Z,1686004644.032 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect] Stopped 2023-06-05T22:37:24.032Z,1686004644.032 [run_backseat_on_surface:Science:ChlThreshTrigger:A.ValueDetect](DEBUG): Uninitialize. 2023-06-05T22:37:24.032Z,1686004644.032 [run_backseat_on_surface:Science:HighestSaltPeakReport] Stopped 2023-06-05T22:37:24.032Z,1686004644.033 [run_backseat_on_surface:Science:HighestSaltPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestSaltPeakReport 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal] Stopped 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:HighestSaltPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectSalinity] Stopped 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectSalinity](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectSalinity 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth] Stopped 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectSalinity:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectFDOM] Stopped 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectFDOM 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-06-05T22:37:24.033Z,1686004644.033 [run_backseat_on_surface:Science:HighestOilPeakReport] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:HighestOilPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestOilPeakReport 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:HighestOilPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectOil] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectOil 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectNO3] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectNO3 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-06-05T22:37:24.034Z,1686004644.034 [run_backseat_on_surface:Science:HighestChlPeakReport] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:HighestChlPeakReport 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize. 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:PeakDetectChl] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:PeakDetectChl 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize. 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:OceanCurrent:A.] Running Loop=1 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:Read_Oil] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:F] Stopped 2023-06-05T22:37:24.035Z,1686004644.035 [run_backseat_on_surface:Science:E] Running Loop=1 2023-06-05T22:37:24.040Z,1686004644.040 [run_backseat_on_surface:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water 2023-06-05T22:37:24.040Z,1686004644.040 [run_backseat_on_surface:Science:D] Stopped 2023-06-05T22:37:24.041Z,1686004644.041 [run_backseat_on_surface:Science:C] Running Loop=1 2023-06-05T22:37:24.041Z,1686004644.041 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature 2023-06-05T22:37:24.042Z,1686004644.042 [run_backseat_on_surface:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity 2023-06-05T22:37:24.042Z,1686004644.042 [run_backseat_on_surface:Science:B] Running Loop=1 2023-06-05T22:37:24.042Z,1686004644.042 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature 2023-06-05T22:37:24.043Z,1686004644.043 [run_backseat_on_surface:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity 2023-06-05T22:37:24.043Z,1686004644.043 [run_backseat_on_surface:Science:A] Stopped 2023-06-05T22:37:24.177Z,1686004644.177 [WetLabsBB2FL](INFO): Powering up 2023-06-05T22:37:24.264Z,1686004644.264 [run_backseat_on_surface:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2023-06-05T22:37:27.345Z,1686004647.345 [CTD_Seabird](ERROR): Failed to parse device response: 2023-06-05T22:37:28.381Z,1686004648.381 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389507 psu 2023-06-05T22:37:30.389Z,1686004650.389 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389471 psu 2023-06-05T22:37:31.381Z,1686004651.381 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389489 psu 2023-06-05T22:37:32.385Z,1686004652.385 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389503 psu 2023-06-05T22:37:33.393Z,1686004653.393 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389510 psu 2023-06-05T22:37:34.382Z,1686004654.382 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389510 psu 2023-06-05T22:37:35.386Z,1686004655.386 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389500 psu 2023-06-05T22:37:36.390Z,1686004656.390 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389490 psu 2023-06-05T22:37:37.394Z,1686004657.394 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389424 psu 2023-06-05T22:37:38.398Z,1686004658.398 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389407 psu 2023-06-05T22:37:39.402Z,1686004659.402 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389390 psu 2023-06-05T22:37:40.406Z,1686004660.406 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389380 psu 2023-06-05T22:37:41.410Z,1686004661.410 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389364 psu 2023-06-05T22:37:42.414Z,1686004662.414 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389289 psu 2023-06-05T22:37:43.418Z,1686004663.418 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389273 psu 2023-06-05T22:37:44.422Z,1686004664.422 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389266 psu 2023-06-05T22:37:45.426Z,1686004665.426 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389250 psu 2023-06-05T22:37:46.430Z,1686004666.430 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389238 psu 2023-06-05T22:37:47.434Z,1686004667.434 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389233 psu 2023-06-05T22:37:48.438Z,1686004668.438 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389226 psu 2023-06-05T22:37:48.736Z,1686004668.736 [CommandExec](IMPORTANT): got command report mod height_above_sea_floor 2023-06-05T22:37:49.442Z,1686004669.442 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389211 psu 2023-06-05T22:37:50.446Z,1686004670.446 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389205 psu 2023-06-05T22:37:51.451Z,1686004671.451 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389196 psu 2023-06-05T22:37:52.454Z,1686004672.454 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389135 psu 2023-06-05T22:37:53.458Z,1686004673.458 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389127 psu 2023-06-05T22:37:54.466Z,1686004674.466 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389133 psu 2023-06-05T22:37:55.469Z,1686004675.469 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389138 psu 2023-06-05T22:37:56.470Z,1686004676.470 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389151 psu 2023-06-05T22:37:57.474Z,1686004677.474 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389155 psu 2023-06-05T22:37:58.478Z,1686004678.478 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389165 psu 2023-06-05T22:37:59.482Z,1686004679.482 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389175 psu 2023-06-05T22:38:00.486Z,1686004680.486 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389183 psu 2023-06-05T22:38:01.493Z,1686004681.493 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389126 psu 2023-06-05T22:38:02.494Z,1686004682.494 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389134 psu 2023-06-05T22:38:03.498Z,1686004683.498 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389125 psu 2023-06-05T22:38:04.241Z,1686004684.241 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5. 2023-06-05T22:38:04.248Z,1686004684.248 [BPC1](INFO): Received data from all battery sticks. 2023-06-05T22:38:04.502Z,1686004684.502 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389114 psu 2023-06-05T22:38:05.506Z,1686004685.506 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389058 psu 2023-06-05T22:38:06.510Z,1686004686.510 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389060 psu 2023-06-05T22:38:07.514Z,1686004687.514 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389072 psu 2023-06-05T22:38:08.518Z,1686004688.518 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389070 psu 2023-06-05T22:38:09.523Z,1686004689.523 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389063 psu 2023-06-05T22:38:10.527Z,1686004690.527 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389050 psu 2023-06-05T22:38:11.531Z,1686004691.531 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389042 psu 2023-06-05T22:38:12.535Z,1686004692.535 [CTD_Seabird](ERROR): Salinity reading out of range: 0.389044 psu 2023-06-05T22:38:13.541Z,1686004693.541 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388981 psu 2023-06-05T22:38:14.542Z,1686004694.542 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388975 psu 2023-06-05T22:38:15.546Z,1686004695.546 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388965 psu 2023-06-05T22:38:16.550Z,1686004696.550 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388957 psu 2023-06-05T22:38:17.554Z,1686004697.554 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388887 psu 2023-06-05T22:38:18.558Z,1686004698.558 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388876 psu 2023-06-05T22:38:19.572Z,1686004699.572 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388861 psu 2023-06-05T22:38:20.566Z,1686004700.566 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388791 psu 2023-06-05T22:38:21.570Z,1686004701.570 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388783 psu 2023-06-05T22:38:21.974Z,1686004701.974 [BackseatComponent](IMPORTANT): IBASE917-01: Running bioacoustics backseat app. Logging to: 20230605T223820 2023-06-05T22:38:22.574Z,1686004702.574 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388773 psu 2023-06-05T22:38:23.578Z,1686004703.578 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388706 psu 2023-06-05T22:38:24.582Z,1686004704.582 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388699 psu 2023-06-05T22:38:24.679Z,1686004704.679 [BackseatComponent](INFO): Created universal data reader for depth with code: 6 2023-06-05T22:38:24.679Z,1686004704.679 [BackseatComponent](INFO): Requesting data from depth (6). Requested size is: 1 2023-06-05T22:38:24.682Z,1686004704.682 [BackseatComponent](INFO): Created universal data reader for latitude with code: 23 2023-06-05T22:38:24.683Z,1686004704.683 [BackseatComponent](INFO): Requesting data from latitude (23). Requested size is: 2 2023-06-05T22:38:24.686Z,1686004704.686 [BackseatComponent](INFO): Created universal data reader for longitude with code: 26 2023-06-05T22:38:24.686Z,1686004704.686 [BackseatComponent](INFO): Requesting data from longitude (26). Requested size is: 3 2023-06-05T22:38:24.693Z,1686004704.693 [BackseatComponent](INFO): Created universal data reader for platform_orientation with code: 52 2023-06-05T22:38:24.694Z,1686004704.694 [BackseatComponent](INFO): Requesting data from platform_orientation (52). Requested size is: 4 2023-06-05T22:38:24.696Z,1686004704.696 [BackseatComponent](INFO): Created universal data reader for platform_pitch_angle with code: 54 2023-06-05T22:38:24.697Z,1686004704.697 [BackseatComponent](INFO): Requesting data from platform_pitch_angle (54). Requested size is: 5 2023-06-05T22:38:24.699Z,1686004704.699 [BackseatComponent](INFO): Created universal data reader for platform_roll_angle with code: 59 2023-06-05T22:38:24.720Z,1686004704.720 [BackseatComponent](INFO): Requesting data from platform_roll_angle (59). Requested size is: 6 2023-06-05T22:38:24.723Z,1686004704.723 [BackseatComponent](INFO): Created universal data reader for platform_x_velocity_wrt_sea_water with code: 70 2023-06-05T22:38:24.723Z,1686004704.723 [BackseatComponent](INFO): Requesting data from platform_x_velocity_wrt_sea_water (70). Requested size is: 7 2023-06-05T22:38:25.586Z,1686004705.586 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388690 psu 2023-06-05T22:38:26.590Z,1686004706.590 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388631 psu 2023-06-05T22:38:27.594Z,1686004707.594 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388635 psu 2023-06-05T22:38:28.598Z,1686004708.598 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388632 psu 2023-06-05T22:38:29.603Z,1686004709.603 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388583 psu 2023-06-05T22:38:30.606Z,1686004710.606 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388602 psu 2023-06-05T22:38:31.610Z,1686004711.610 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388575 psu 2023-06-05T22:38:32.614Z,1686004712.614 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388594 psu 2023-06-05T22:38:33.618Z,1686004713.618 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388565 psu 2023-06-05T22:38:34.622Z,1686004714.622 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388577 psu 2023-06-05T22:38:35.626Z,1686004715.626 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388582 psu 2023-06-05T22:38:36.630Z,1686004716.630 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388525 psu 2023-06-05T22:38:37.634Z,1686004717.634 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388521 psu 2023-06-05T22:38:38.638Z,1686004718.638 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388457 psu 2023-06-05T22:38:39.642Z,1686004719.642 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388452 psu 2023-06-05T22:38:40.646Z,1686004720.646 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388392 psu 2023-06-05T22:38:41.650Z,1686004721.650 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388390 psu 2023-06-05T22:38:42.654Z,1686004722.654 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388340 psu 2023-06-05T22:38:43.658Z,1686004723.658 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388339 psu 2023-06-05T22:38:44.666Z,1686004724.666 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388287 psu 2023-06-05T22:38:45.666Z,1686004725.666 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388293 psu 2023-06-05T22:38:46.670Z,1686004726.670 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388226 psu 2023-06-05T22:38:47.073Z,1686004727.073 [run_backseat_on_surface:B.BackseatDriver](INFO): Created data writer for _.height_above_sea_floor with code: 1906 2023-06-05T22:38:47.674Z,1686004727.674 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388217 psu 2023-06-05T22:38:47.953Z,1686004727.953 [Reporter](INFO): height_above_sea_floor 2.170000 m 2023-06-05T22:38:48.678Z,1686004728.678 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388149 psu 2023-06-05T22:38:49.682Z,1686004729.682 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388150 psu 2023-06-05T22:38:50.686Z,1686004730.686 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388158 psu 2023-06-05T22:38:51.690Z,1686004731.690 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388101 psu 2023-06-05T22:38:52.694Z,1686004732.694 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388095 psu 2023-06-05T22:38:53.698Z,1686004733.698 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388091 psu 2023-06-05T22:38:54.703Z,1686004734.703 [CTD_Seabird](ERROR): Salinity reading out of range: 0.388090 psu 2023-06-05T22:38:55.184Z,1686004735.184 [CommandExec](IMPORTANT): got command stop 2023-06-05T22:38:55.185Z,1686004735.185 [CommandExec](IMPORTANT): Scheduling is paused 2023-06-05T22:38:55.185Z,1686004735.185 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop 2023-06-05T22:38:55.565Z,1686004735.565 [MissionManager](INFO): MissionManager is completed. 2023-06-05T22:38:55.565Z,1686004735.565 [MissionManager](INFO): Uninitializing Mission run_backseat_on_surface 2023-06-05T22:38:55.565Z,1686004735.565 [run_backseat_on_surface] Stopped 2023-06-05T22:38:55.565Z,1686004735.565 [run_backseat_on_surface](DEBUG): Aggregate::uninitialize run_backseat_on_surface 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:B] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:C] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:E] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:OceanCurrent] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:OceanCurrent](DEBUG): Aggregate::uninitialize run_backseat_on_surface:Science:OceanCurrent 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:Science:OceanCurrent:A.] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:B.BackseatDriver] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:B.BackseatDriver](DEBUG): Uninitialize. 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:C.GoToSurface] Stopped 2023-06-05T22:38:55.566Z,1686004735.566 [run_backseat_on_surface:C.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2023-06-05T22:38:55.567Z,1686004735.567 [run_backseat_on_surface:CheckIn] Stopped 2023-06-05T22:38:55.567Z,1686004735.567 [run_backseat_on_surface:CheckIn](DEBUG): Aggregate::uninitialize run_backseat_on_surface:CheckIn 2023-06-05T22:38:55.567Z,1686004735.567 [run_backseat_on_surface:CheckIn:Read_GPS] Stopped 2023-06-05T22:38:55.932Z,1686004735.932 [BioacousticsDataBridge](DEBUG): Backseat LCM timeout, waiting for message to resume 2023-06-05T22:38:55.939Z,1686004735.939 [BackseatComponent](INFO): Sent LCM shutdown request. 2023-06-05T22:38:55.970Z,1686004735.970 [MissionManager](IMPORTANT): Started mission Default 2023-06-05T22:38:55.970Z,1686004735.970 [Default] Running Loop=1 2023-06-05T22:38:55.970Z,1686004735.970 [Default](DEBUG): Aggregate::initialize Default 2023-06-05T22:38:55.970Z,1686004735.970 [Default:B.GoToSurface] Running Loop=1 2023-06-05T22:38:55.970Z,1686004735.970 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2023-06-05T22:38:55.971Z,1686004735.971 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2023-06-05T22:38:55.971Z,1686004735.971 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2023-06-05T22:38:55.971Z,1686004735.971 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2023-06-05T22:38:55.972Z,1686004735.972 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2023-06-05T22:38:55.972Z,1686004735.972 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2023-06-05T22:38:55.973Z,1686004735.973 [Default:A.Wait] Running Loop=1 2023-06-05T22:38:55.973Z,1686004735.973 [Default:A.Wait](DEBUG): Initialize Wait Component. 2023-06-05T22:38:56.029Z,1686004736.029 [WetLabsBB2FL](INFO): Powering down 2023-06-05T22:38:59.584Z,1686004739.584 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2023-06-05T22:38:59.584Z,1686004739.584 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:38:59.611Z,1686004739.611 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:38:59.998Z,1686004739.998 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:38:59.998Z,1686004739.998 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2023-06-05T22:39:09.290Z,1686004749.290 [Default:A.Wait](INFO): Done Waiting. 2023-06-05T22:39:09.290Z,1686004749.290 [Default:A.Wait] Stopped 2023-06-05T22:39:09.290Z,1686004749.290 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T22:39:09.687Z,1686004749.687 [Default:CheckIn] Running Loop=1 2023-06-05T22:39:09.687Z,1686004749.687 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T22:39:09.687Z,1686004749.687 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T22:39:11.045Z,1686004751.045 [BackseatComponent](INFO): Powering down and starting shutdown timer (15.00 sec). 2023-06-05T22:39:11.045Z,1686004751.045 [BackseatComponent](INFO): Unsubscribing from LCM channels. 2023-06-05T22:39:11.046Z,1686004751.046 [LcmUniversalReporter](INFO): Deactivating messaging. 2023-06-05T22:39:58.966Z,1686004798.966 [Power24vConverter](INFO): Powering down. 2023-06-05T22:40:01.795Z,1686004801.795 [Sonardyne_Nano](INFO): Command indicated, sending VS 2023-06-05T22:40:02.213Z,1686004802.213 [Sonardyne_Nano](INFO): Received response:>VS:2201,HPR45,EXT,TILT,BT1;LI_ION;VLT4.0;IDC-12.5;CAP3.4/77;T21.6 2023-06-05T22:40:31.597Z,1686004831.597 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent 2023-06-05T22:40:31.598Z,1686004831.598 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 % 2023-06-05T22:40:39.366Z,1686004839.366 [NAL9602](FAULT): GPS failed to acquire within timeout. 2023-06-05T22:40:39.366Z,1686004839.366 [NAL9602] Data Fault, FailCount= 1 2023-06-05T22:40:39.366Z,1686004839.366 [NAL9602](ERROR): Data Fault 2023-06-05T22:40:39.405Z,1686004839.405 [CBIT](ERROR): Data Fault in component: NAL9602 2023-06-05T22:40:39.762Z,1686004839.762 [NAL9602](INFO): Powering down 2023-06-05T22:40:40.602Z,1686004840.602 [CBIT](INFO): Clearing failed state for component NAL9602 2023-06-05T22:40:40.602Z,1686004840.602 [NAL9602] No Fault, FailCount= 1 2023-06-05T22:41:10.062Z,1686004870.062 [NAL9602](INFO): Powering up NAL9602 2023-06-05T22:41:15.580Z,1686004875.580 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting. 2023-06-05T22:41:17.588Z,1686004877.588 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.423008 2023-06-05T22:41:20.977Z,1686004880.977 [NAL9602](INFO): NAL9602 initialized 2023-06-05T22:41:30.836Z,1686004890.836 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003096 2023-06-05T22:42:00.600Z,1686004920.600 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2023-06-05T22:42:00.600Z,1686004920.600 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:42:00.640Z,1686004920.640 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:42:01.010Z,1686004921.010 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:42:01.010Z,1686004921.010 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2023-06-05T22:44:09.943Z,1686005049.943 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-05T22:39:09.7Z 2023-06-05T22:44:09.943Z,1686005049.943 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T22:44:09.943Z,1686005049.943 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T22:44:10.331Z,1686005050.331 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2023-06-05T22:44:16.951Z,1686005056.951 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230605T222945/Courier0004.lzma 2023-06-05T22:44:18.958Z,1686005058.958 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0004.lzma.bak 2023-06-05T22:44:18.958Z,1686005058.958 [DataOverHttps](INFO): SBD MOMSN=18398085 2023-06-05T22:44:37.820Z,1686005077.820 [DataOverHttps](INFO): Sending 510 bytes from file Logs/20230605T222945/Express0005.lzma 2023-06-05T22:44:38.822Z,1686005078.822 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0005.lzma.bak 2023-06-05T22:44:38.822Z,1686005078.822 [DataOverHttps](INFO): SBD MOMSN=18398087 2023-06-05T22:44:40.273Z,1686005080.273 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T22:44:40.273Z,1686005080.273 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T22:44:40.273Z,1686005080.273 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T22:45:01.772Z,1686005101.772 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2023-06-05T22:45:01.772Z,1686005101.772 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:45:01.783Z,1686005101.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:45:02.184Z,1686005102.184 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:45:02.185Z,1686005102.185 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2023-06-05T22:48:02.792Z,1686005282.792 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2023-06-05T22:48:02.792Z,1686005282.792 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2023-06-05T22:48:02.803Z,1686005282.803 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2023-06-05T22:48:03.194Z,1686005283.194 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2023-06-05T22:48:03.194Z,1686005283.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2023-06-05T22:49:40.986Z,1686005380.986 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-05T22:49:40.986Z,1686005380.986 [Default:CheckIn:C.Wait] Stopped 2023-06-05T22:49:40.986Z,1686005380.986 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T22:49:40.987Z,1686005380.987 [Default:CheckIn:D] Running Loop=1 2023-06-05T22:49:41.382Z,1686005381.382 [Default:CheckIn:D] Stopped 2023-06-05T22:49:41.382Z,1686005381.382 [Default:CheckIn:E] Running Loop=1 2023-06-05T22:49:41.769Z,1686005381.769 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.756857 min 2023-06-05T22:49:41.769Z,1686005381.769 [Default:CheckIn:E] Stopped 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn] Stopped 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn](INFO): Running loop #2 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn] Running Loop=2 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T22:49:41.770Z,1686005381.770 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T22:50:01.977Z,1686005401.977 [Sonardyne_Nano](INFO): Command indicated, sending VS 2023-06-05T22:50:02.377Z,1686005402.377 [Sonardyne_Nano](INFO): Received response:>VS:2201,HPR45,EXT,TILT,BT1;LI_ION;VLT4.0;IDC-17.5;CAP3.4/77;T21.6 2023-06-05T22:50:31.867Z,1686005431.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225031.00,A,3648.16534,N,12147.25392,W,3.752,354.05,050623,,,A*7F 2023-06-05T22:50:31.871Z,1686005431.871 [NAL9602](INFO): GPS fix at 20230605T225031: (36.802756, -121.787565) 2023-06-05T22:50:31.986Z,1686005431.986 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T22:50:31.986Z,1686005431.986 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T22:50:43.892Z,1686005443.892 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20230605T222945/Courier0007.lzma 2023-06-05T22:50:44.718Z,1686005444.718 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0007.lzma.bak 2023-06-05T22:50:44.718Z,1686005444.718 [DataOverHttps](INFO): SBD MOMSN=18398113 2023-06-05T22:51:04.013Z,1686005464.013 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-05T22:51:06.048Z,1686005466.048 [DataOverHttps](INFO): Sending 350 bytes from file Logs/20230605T222945/Express0008.lzma 2023-06-05T22:51:07.050Z,1686005467.050 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0008.lzma.bak 2023-06-05T22:51:07.050Z,1686005467.050 [DataOverHttps](INFO): SBD MOMSN=18398120 2023-06-05T22:51:08.509Z,1686005468.509 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T22:51:08.509Z,1686005468.509 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T22:51:08.509Z,1686005468.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T22:56:09.078Z,1686005769.078 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-05T22:56:09.078Z,1686005769.078 [Default:CheckIn:C.Wait] Stopped 2023-06-05T22:56:09.078Z,1686005769.078 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T22:56:09.078Z,1686005769.078 [Default:CheckIn:D] Running Loop=1 2023-06-05T22:56:09.491Z,1686005769.491 [Default:CheckIn:D] Stopped 2023-06-05T22:56:09.491Z,1686005769.491 [Default:CheckIn:E] Running Loop=1 2023-06-05T22:56:09.894Z,1686005769.894 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.225338 min 2023-06-05T22:56:09.894Z,1686005769.894 [Default:CheckIn:E] Stopped 2023-06-05T22:56:09.894Z,1686005769.894 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-05T22:56:09.894Z,1686005769.894 [Default:CheckIn] Stopped 2023-06-05T22:56:09.895Z,1686005769.895 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T22:56:09.895Z,1686005769.895 [Default:CheckIn](INFO): Running loop #3 2023-06-05T22:56:09.895Z,1686005769.895 [Default:CheckIn] Running Loop=3 2023-06-05T22:56:09.895Z,1686005769.895 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T22:56:09.895Z,1686005769.895 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T22:56:11.903Z,1686005771.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225611.00,A,3648.14988,N,12147.21947,W,0.019,80.91,050623,,,A*48 2023-06-05T22:56:11.905Z,1686005771.905 [NAL9602](INFO): GPS fix at 20230605T225611: (36.802498, -121.786991) 2023-06-05T22:56:11.915Z,1686005771.915 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T22:56:11.915Z,1686005771.915 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T22:56:18.759Z,1686005778.759 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230605T222945/Courier0010.lzma 2023-06-05T22:56:19.762Z,1686005779.762 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0010.lzma.bak 2023-06-05T22:56:19.762Z,1686005779.762 [DataOverHttps](INFO): SBD MOMSN=18398131 2023-06-05T22:56:32.931Z,1686005792.931 [NAL9602](INFO): SBD MO Status=2, MOMSN=12047, MT Status=2, MTMSN=0 2023-06-05T22:56:32.931Z,1686005792.931 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-05T22:56:39.431Z,1686005799.431 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20230605T222945/Express0011.lzma 2023-06-05T22:56:40.434Z,1686005800.434 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0011.lzma.bak 2023-06-05T22:56:40.434Z,1686005800.434 [DataOverHttps](INFO): SBD MOMSN=18398134 2023-06-05T22:56:41.877Z,1686005801.877 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T22:56:41.877Z,1686005801.877 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T22:56:41.877Z,1686005801.877 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T22:57:25.118Z,1686005845.118 [NAL9602](INFO): SBD MO Status=2, MOMSN=12047, MT Status=2, MTMSN=0 2023-06-05T22:57:25.118Z,1686005845.118 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-05T23:00:02.327Z,1686006002.327 [Sonardyne_Nano](INFO): Command indicated, sending VS 2023-06-05T23:00:02.739Z,1686006002.739 [Sonardyne_Nano](INFO): Received response:>VS:2201,HPR45,EXT,TILT,BT1;LI_ION;VLT4.0;IDC-17.5;CAP3.4/77;T21.2 2023-06-05T23:00:21.873Z,1686006021.873 [DataOverHttps](IMPORTANT): SBD MTMSN=20230605T230020 2023-06-05T23:00:30.356Z,1686006030.356 [DataOverHttps](INFO): Received command: ibit 2023-06-05T23:00:30.358Z,1686006030.358 [CommandExec](IMPORTANT): got command ibit 2023-06-05T23:00:30.670Z,1686006030.670 [IBIT](IMPORTANT): Beginning Initiated BIT 2023-06-05T23:00:30.671Z,1686006030.671 [IBIT](IMPORTANT): Beginning control surface checks. 2023-06-05T23:00:30.682Z,1686006030.682 [CBIT](IMPORTANT): Beginning ground fault scan 2023-06-05T23:00:31.823Z,1686006031.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=12047, MT Status=2, MTMSN=0 2023-06-05T23:00:31.823Z,1686006031.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-05T23:00:33.553Z,1686006033.553 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes. 2023-06-05T23:00:41.571Z,1686006041.571 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.005983 CHAN A1 (24V) N/A reporting off CHAN A2 (12V): -0.004461 CHAN A3 (5V): -0.002940 CHAN B0 (3.3V): -0.000858 CHAN B1 (3.15aV): -0.001184 CHAN B2 (3.15bV): -0.000616 CHAN B3 (GND): 0.000197 OPEN: 0.003601 Full Scale: +/- 1 mA 2023-06-05T23:00:57.678Z,1686006057.678 [NAL9602](INFO): SBD MO Status=0, MOMSN=12047, MT Status=0, MTMSN=0 2023-06-05T23:00:57.679Z,1686006057.679 [NAL9602](INFO): No messages in MT queue 2023-06-05T23:00:58.897Z,1686006058.897 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230058.00,A,3648.15095,N,12147.21926,W,0.136,80.91,050623,,,D*4D 2023-06-05T23:00:58.899Z,1686006058.899 [NAL9602](INFO): GPS fix at 20230605T230058: (36.802516, -121.786988) 2023-06-05T23:01:02.127Z,1686006062.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230101.00,A,3648.15103,N,12147.21918,W,0.078,80.91,050623,,,D*48 2023-06-05T23:01:02.129Z,1686006062.129 [NAL9602](INFO): GPS fix at 20230605T230101: (36.802517, -121.786986) 2023-06-05T23:01:04.947Z,1686006064.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230104.00,A,3648.15106,N,12147.21911,W,0.078,80.91,050623,,,D*41 2023-06-05T23:01:04.949Z,1686006064.949 [NAL9602](INFO): GPS fix at 20230605T230104: (36.802518, -121.786985) 2023-06-05T23:01:08.183Z,1686006068.183 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230107.00,A,3648.15120,N,12147.21899,W,0.253,80.91,050623,,,D*4C 2023-06-05T23:01:08.186Z,1686006068.186 [NAL9602](INFO): GPS fix at 20230605T230107: (36.802520, -121.786983) 2023-06-05T23:01:11.011Z,1686006071.011 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230110.00,A,3648.15139,N,12147.21882,W,0.253,80.91,050623,,,D*48 2023-06-05T23:01:11.013Z,1686006071.013 [NAL9602](INFO): GPS fix at 20230605T230110: (36.802523, -121.786980) 2023-06-05T23:01:13.844Z,1686006073.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230113.00,A,3648.15162,N,12147.21869,W,0.253,80.91,050623,,,D*40 2023-06-05T23:01:13.846Z,1686006073.846 [NAL9602](INFO): GPS fix at 20230605T230113: (36.802527, -121.786978) 2023-06-05T23:01:17.071Z,1686006077.071 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230116.00,A,3648.15187,N,12147.21856,W,0.253,80.91,050623,,,D*42 2023-06-05T23:01:17.073Z,1686006077.073 [NAL9602](INFO): GPS fix at 20230605T230116: (36.802531, -121.786976) 2023-06-05T23:01:17.122Z,1686006077.122 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 2 Latitude: 36.802532 Longitude: -121.786980 2023-06-05T23:01:17.503Z,1686006077.503 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 14.470000 2023-06-05T23:01:17.503Z,1686006077.503 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2023-06-05T23:01:17.504Z,1686006077.504 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2023-06-05T23:01:17.904Z,1686006077.904 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2023-06-05T23:01:17.905Z,1686006077.905 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2023-06-05T23:01:17.905Z,1686006077.905 [IBIT](IMPORTANT): Pressure:9.298927 PSI 2023-06-05T23:01:17.905Z,1686006077.905 [IBIT](IMPORTANT): Humidity:6.928336 % 2023-06-05T23:01:18.293Z,1686006078.293 [IBIT](IMPORTANT): Vehicle Pitch:-1.549976 degrees 2023-06-05T23:01:18.293Z,1686006078.293 [IBIT](IMPORTANT): Vehicle Roll:2.833045 degrees 2023-06-05T23:01:18.293Z,1686006078.293 [IBIT](IMPORTANT): Vehicle Heading:211.506943 degrees 2023-06-05T23:01:18.703Z,1686006078.703 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2023-06-05T23:01:18.704Z,1686006078.704 [IBIT](IMPORTANT): buoyancyNeutral: 184.896484 cc 2023-06-05T23:01:18.704Z,1686006078.704 [IBIT](IMPORTANT): massDefault: 2.500000 cm 2023-06-05T23:01:18.704Z,1686006078.704 [IBIT](IMPORTANT): stopDepth: 275.000000 m 2023-06-05T23:01:18.705Z,1686006078.705 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2023-06-05T23:01:18.705Z,1686006078.705 [IBIT](IMPORTANT): IBIT PASSED 2023-06-05T23:01:42.531Z,1686006102.531 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-05T23:01:42.531Z,1686006102.531 [Default:CheckIn:C.Wait] Stopped 2023-06-05T23:01:42.531Z,1686006102.531 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T23:01:42.531Z,1686006102.531 [Default:CheckIn:D] Running Loop=1 2023-06-05T23:01:42.944Z,1686006102.944 [Default:CheckIn:D] Stopped 2023-06-05T23:01:42.944Z,1686006102.944 [Default:CheckIn:E] Running Loop=1 2023-06-05T23:01:43.334Z,1686006103.334 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.782892 min 2023-06-05T23:01:43.334Z,1686006103.334 [Default:CheckIn:E] Stopped 2023-06-05T23:01:43.334Z,1686006103.334 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-05T23:01:43.334Z,1686006103.334 [Default:CheckIn] Stopped 2023-06-05T23:01:43.334Z,1686006103.334 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T23:01:43.335Z,1686006103.335 [Default:CheckIn](INFO): Running loop #4 2023-06-05T23:01:43.335Z,1686006103.335 [Default:CheckIn] Running Loop=4 2023-06-05T23:01:43.335Z,1686006103.335 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T23:01:43.335Z,1686006103.335 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T23:01:45.347Z,1686006105.347 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230144.00,A,3648.15380,N,12147.21740,W,0.603,32.74,050623,,,D*4B 2023-06-05T23:01:45.349Z,1686006105.349 [NAL9602](INFO): GPS fix at 20230605T230144: (36.802563, -121.786957) 2023-06-05T23:01:45.371Z,1686006105.371 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T23:01:45.371Z,1686006105.371 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T23:01:53.191Z,1686006113.191 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230605T222945/Courier0013.lzma 2023-06-05T23:01:54.194Z,1686006114.194 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0013.lzma.bak 2023-06-05T23:01:54.194Z,1686006114.194 [DataOverHttps](INFO): SBD MOMSN=18398148 2023-06-05T23:02:09.811Z,1686006129.811 [DataOverHttps](INFO): Sending 831 bytes from file Logs/20230605T222945/Express0014.lzma 2023-06-05T23:02:10.810Z,1686006130.810 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0014.lzma.bak 2023-06-05T23:02:10.810Z,1686006130.810 [DataOverHttps](INFO): SBD MOMSN=18398151 2023-06-05T23:02:17.664Z,1686006137.664 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-05T23:02:27.811Z,1686006147.811 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20230605T222945/Express0017.lzma 2023-06-05T23:02:28.814Z,1686006148.814 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0017.lzma.bak 2023-06-05T23:02:28.814Z,1686006148.814 [DataOverHttps](INFO): SBD MOMSN=18398177 2023-06-05T23:02:30.210Z,1686006150.210 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T23:02:30.210Z,1686006150.210 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T23:02:30.210Z,1686006150.210 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T23:07:30.871Z,1686006450.871 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-05T23:07:30.871Z,1686006450.871 [Default:CheckIn:C.Wait] Stopped 2023-06-05T23:07:30.871Z,1686006450.871 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T23:07:30.871Z,1686006450.871 [Default:CheckIn:D] Running Loop=1 2023-06-05T23:07:31.271Z,1686006451.271 [Default:CheckIn:D] Stopped 2023-06-05T23:07:31.271Z,1686006451.271 [Default:CheckIn:E] Running Loop=1 2023-06-05T23:07:31.681Z,1686006451.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.588340 min 2023-06-05T23:07:31.681Z,1686006451.681 [Default:CheckIn:E] Stopped 2023-06-05T23:07:31.681Z,1686006451.681 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-05T23:07:31.681Z,1686006451.681 [Default:CheckIn] Stopped 2023-06-05T23:07:31.681Z,1686006451.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T23:07:31.682Z,1686006451.682 [Default:CheckIn](INFO): Running loop #5 2023-06-05T23:07:31.682Z,1686006451.682 [Default:CheckIn] Running Loop=5 2023-06-05T23:07:31.682Z,1686006451.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T23:07:31.682Z,1686006451.682 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T23:07:33.691Z,1686006453.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230732.00,A,3648.48032,N,12147.10824,W,5.210,349.72,050623,,,D*7B 2023-06-05T23:07:33.693Z,1686006453.693 [NAL9602](INFO): GPS fix at 20230605T230732: (36.808005, -121.785137) 2023-06-05T23:07:33.737Z,1686006453.737 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T23:07:33.737Z,1686006453.737 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T23:07:41.135Z,1686006461.135 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230605T222945/Courier0019.lzma 2023-06-05T23:07:42.138Z,1686006462.138 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0019.lzma.bak 2023-06-05T23:07:42.138Z,1686006462.138 [DataOverHttps](INFO): SBD MOMSN=18398188 2023-06-05T23:07:51.466Z,1686006471.466 [NAL9602](INFO): SBD MO Status=2, MOMSN=12048, MT Status=2, MTMSN=0 2023-06-05T23:07:51.467Z,1686006471.467 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2023-06-05T23:07:57.931Z,1686006477.931 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20230605T222945/Express0020.lzma 2023-06-05T23:07:58.934Z,1686006478.934 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0020.lzma.bak 2023-06-05T23:07:58.934Z,1686006478.934 [DataOverHttps](INFO): SBD MOMSN=18398191 2023-06-05T23:08:00.367Z,1686006480.367 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T23:08:00.367Z,1686006480.367 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T23:08:00.367Z,1686006480.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T23:08:16.519Z,1686006496.519 [NAL9602](INFO): SBD MO Status=0, MOMSN=12048, MT Status=0, MTMSN=0 2023-06-05T23:08:16.519Z,1686006496.519 [NAL9602](INFO): No messages in MT queue 2023-06-05T23:08:47.282Z,1686006527.282 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-05T23:10:02.422Z,1686006602.422 [Sonardyne_Nano](INFO): Command indicated, sending VS 2023-06-05T23:10:02.831Z,1686006602.831 [Sonardyne_Nano](INFO): Received response:>VS:2201,HPR45,EXT,TILT,BT1;LI_ION;VLT4.0;IDC-12.5;CAP3.4/77;T20.8 2023-06-05T23:10:29.269Z,1686006629.269 [DataOverHttps](IMPORTANT): SBD MTMSN=20230605T231028 2023-06-05T23:10:39.764Z,1686006639.764 [DataOverHttps](INFO): Received command: configSet Express linearApproximation height_above_sea_floor 5 meter persist 2023-06-05T23:10:41.189Z,1686006641.189 [CommandExec](IMPORTANT): got command configSet Express linearApproximation height_above_sea_floor 5 meter persist 2023-06-05T23:13:01.097Z,1686006781.097 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2023-06-05T23:13:01.097Z,1686006781.097 [Default:CheckIn:C.Wait] Stopped 2023-06-05T23:13:01.097Z,1686006781.097 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2023-06-05T23:13:01.098Z,1686006781.098 [Default:CheckIn:D] Running Loop=1 2023-06-05T23:13:01.513Z,1686006781.513 [Default:CheckIn:D] Stopped 2023-06-05T23:13:01.513Z,1686006781.513 [Default:CheckIn:E] Running Loop=1 2023-06-05T23:13:01.905Z,1686006781.905 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.092375 min 2023-06-05T23:13:01.905Z,1686006781.905 [Default:CheckIn:E] Stopped 2023-06-05T23:13:01.905Z,1686006781.905 [Default:CheckIn](INFO): Completed Default:CheckIn 2023-06-05T23:13:01.905Z,1686006781.905 [Default:CheckIn] Stopped 2023-06-05T23:13:01.905Z,1686006781.905 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2023-06-05T23:13:01.906Z,1686006781.906 [Default:CheckIn](INFO): Running loop #6 2023-06-05T23:13:01.906Z,1686006781.906 [Default:CheckIn] Running Loop=6 2023-06-05T23:13:01.906Z,1686006781.906 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2023-06-05T23:13:01.906Z,1686006781.906 [Default:CheckIn:Read_GPS] Running Loop=1 2023-06-05T23:13:03.919Z,1686006783.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231303.00,A,3648.26486,N,12148.11581,W,14.268,284.29,050623,,,D*42 2023-06-05T23:13:03.921Z,1686006783.921 [NAL9602](INFO): GPS fix at 20230605T231303: (36.804414, -121.801930) 2023-06-05T23:13:03.932Z,1686006783.932 [Default:CheckIn:Read_GPS] Stopped 2023-06-05T23:13:03.932Z,1686006783.932 [Default:CheckIn:Read_Iridium] Running Loop=1 2023-06-05T23:13:11.619Z,1686006791.619 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230605T222945/Courier0022.lzma 2023-06-05T23:13:12.622Z,1686006792.622 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0022.lzma.bak 2023-06-05T23:13:12.622Z,1686006792.622 [DataOverHttps](INFO): SBD MOMSN=18398206 2023-06-05T23:13:20.074Z,1686006800.074 [NAL9602](INFO): SBD MO Status=0, MOMSN=12049, MT Status=0, MTMSN=0 2023-06-05T23:13:20.075Z,1686006800.075 [NAL9602](INFO): No messages in MT queue 2023-06-05T23:13:32.295Z,1686006812.295 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230605T222945/Courier0025.lzma 2023-06-05T23:13:33.298Z,1686006813.298 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Courier0025.lzma.bak 2023-06-05T23:13:33.298Z,1686006813.298 [DataOverHttps](INFO): SBD MOMSN=18398209 2023-06-05T23:13:50.777Z,1686006830.777 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-05T23:14:18.524Z,1686006858.524 [DataOverHttps](INFO): Sending 533 bytes from file Logs/20230605T222945/Express0023.lzma 2023-06-05T23:14:19.526Z,1686006859.526 [DataOverHttps](INFO): Moved sent file to Logs/20230605T222945/Express0023.lzma.bak 2023-06-05T23:14:19.526Z,1686006859.526 [DataOverHttps](INFO): SBD MOMSN=18398211 2023-06-05T23:15:00.036Z,1686006900.036 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:15:29.153Z,1686006929.153 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239189 2023-06-05T23:16:00.276Z,1686006960.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:16:13.454Z,1686006973.454 [NAL9602](INFO): SBD MO Status=1, MOMSN=12050, MT Status=0, MTMSN=0 2023-06-05T23:16:13.516Z,1686006973.516 [NAL9602](INFO): Sent 70 bytes from file Logs/20230605T222945/Express0026.lzma 2023-06-05T23:16:13.517Z,1686006973.517 [NAL9602](INFO): Packets left to send: 0 2023-06-05T23:16:22.750Z,1686006982.750 [NAL9602](INFO): SBD MO Status=0, MOMSN=12051, MT Status=0, MTMSN=0 2023-06-05T23:16:22.838Z,1686006982.838 [Default:CheckIn:Read_Iridium] Stopped 2023-06-05T23:16:22.838Z,1686006982.838 [Default:CheckIn:C.Wait] Running Loop=1 2023-06-05T23:16:22.838Z,1686006982.838 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2023-06-05T23:16:35.420Z,1686006995.420 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:16:53.455Z,1686007013.455 [NAL9602](INFO): Not Powering down - fast GPS 2023-06-05T23:17:10.572Z,1686007030.572 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:17:45.712Z,1686007065.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:18:20.852Z,1686007100.852 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:18:56.000Z,1686007136.000 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting. 2023-06-05T23:19:08.320Z,1686007148.320 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002938 2023-06-05T23:19:43.781Z,1686007183.781 [DataOverHttps](IMPORTANT): SBD MTMSN=20230605T231942 2023-06-05T23:19:51.203Z,1686007191.203 [DataOverHttps](INFO): Received command: restart logs