2024-10-22T18:25:44.443Z,1729621544.443 [Supervisor](DEBUG): Initializing supervisor. 2024-10-22T18:25:44.447Z,1729621544.447 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-10-22T18:25:44.448Z,1729621544.448 [SyncHandler](INFO): Protected caller Thread ID is 827 2024-10-22T18:25:44.448Z,1729621544.448 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-10-22T18:25:44.449Z,1729621544.449 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-10-22T18:25:44.450Z,1729621544.450 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 828 2024-10-22T18:25:44.454Z,1729621544.454 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-10-22T18:25:44.471Z,1729621544.471 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-10-22T18:25:44.472Z,1729621544.472 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-10-22T18:25:44.472Z,1729621544.472 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 829 2024-10-22T18:25:44.477Z,1729621544.477 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-10-22T18:25:44.478Z,1729621544.478 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-10-22T18:25:44.478Z,1729621544.478 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 830 2024-10-22T18:25:44.480Z,1729621544.480 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-10-22T18:25:44.481Z,1729621544.481 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-10-22T18:25:44.481Z,1729621544.481 [logger ThreadHandler](INFO): Protected caller Thread ID is 831 2024-10-22T18:25:44.485Z,1729621544.485 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-10-22T18:25:44.485Z,1729621544.485 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-10-22T18:25:44.488Z,1729621544.488 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-10-22T18:25:44.727Z,1729621544.727 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-10-22T18:25:44.729Z,1729621544.729 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-10-22T18:25:44.953Z,1729621544.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-10-22T18:25:44.954Z,1729621544.954 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-10-22T18:25:45.059Z,1729621545.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-10-22T18:25:45.060Z,1729621545.060 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-10-22T18:25:45.259Z,1729621545.259 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-10-22T18:25:45.261Z,1729621545.261 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-10-22T18:25:45.776Z,1729621545.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-10-22T18:25:45.778Z,1729621545.778 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-10-22T18:25:45.896Z,1729621545.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-10-22T18:25:45.898Z,1729621545.898 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-10-22T18:25:46.185Z,1729621546.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-10-22T18:25:46.187Z,1729621546.187 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-10-22T18:25:46.414Z,1729621546.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-10-22T18:25:46.414Z,1729621546.414 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-10-22T18:25:46.969Z,1729621546.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-10-22T18:25:46.971Z,1729621546.971 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-10-22T18:25:47.405Z,1729621547.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-10-22T18:25:47.406Z,1729621547.406 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-10-22T18:25:47.496Z,1729621547.496 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-10-22T18:25:47.878Z,1729621547.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-10-22T18:25:47.879Z,1729621547.879 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-10-22T18:25:48.181Z,1729621548.181 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-10-22T18:25:48.182Z,1729621548.182 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-10-22T18:25:56.473Z,1729621556.473 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-10-22T18:25:56.474Z,1729621556.474 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-10-22T18:25:56.838Z,1729621556.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-10-22T18:25:56.838Z,1729621556.838 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-10-22T18:25:56.932Z,1729621556.932 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/ 2024-10-22T18:25:56.934Z,1729621556.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg 2024-10-22T18:25:57.030Z,1729621557.030 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg 2024-10-22T18:25:57.147Z,1729621557.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg 2024-10-22T18:25:57.263Z,1729621557.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Navigation.cfg 2024-10-22T18:25:57.380Z,1729621557.380 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg 2024-10-22T18:25:57.479Z,1729621557.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg 2024-10-22T18:25:57.576Z,1729621557.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg 2024-10-22T18:25:57.690Z,1729621557.690 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg 2024-10-22T18:25:57.847Z,1729621557.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg 2024-10-22T18:25:58.020Z,1729621558.020 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg 2024-10-22T18:25:58.129Z,1729621558.129 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg 2024-10-22T18:25:58.404Z,1729621558.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-10-22T18:25:58.404Z,1729621558.404 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-10-22T18:25:58.419Z,1729621558.419 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-10-22T18:25:58.817Z,1729621558.817 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-10-22T18:25:58.818Z,1729621558.818 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-10-22T18:25:59.666Z,1729621559.666 [AHRS_M2] Loaded 2024-10-22T18:25:59.666Z,1729621559.666 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-10-22T18:25:59.919Z,1729621559.919 [BackseatComponent] Loaded 2024-10-22T18:25:59.919Z,1729621559.919 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2024-10-22T18:25:59.920Z,1729621559.920 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408914E0 2024-10-22T18:25:59.921Z,1729621559.921 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 913 2024-10-22T18:25:59.923Z,1729621559.923 [LcmUniversalReporter] Loaded 2024-10-22T18:25:59.924Z,1729621559.924 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2024-10-22T18:26:00.722Z,1729621560.722 [BPC1] Loaded 2024-10-22T18:26:00.722Z,1729621560.722 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-10-22T18:26:00.811Z,1729621560.811 [DAT] Loaded 2024-10-22T18:26:00.812Z,1729621560.812 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2024-10-22T18:26:00.812Z,1729621560.812 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408C14E0 2024-10-22T18:26:00.813Z,1729621560.813 [DAT ThreadHandler](INFO): Protected caller Thread ID is 914 2024-10-22T18:26:00.956Z,1729621560.956 [DataOverHttps] Loaded 2024-10-22T18:26:00.956Z,1729621560.956 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-10-22T18:26:00.957Z,1729621560.957 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408F14E0 2024-10-22T18:26:00.958Z,1729621560.958 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 915 2024-10-22T18:26:00.980Z,1729621560.980 [Depth_Keller] Loaded 2024-10-22T18:26:00.980Z,1729621560.980 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-10-22T18:26:00.985Z,1729621560.985 [DropWeight] Loaded 2024-10-22T18:26:00.985Z,1729621560.985 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-10-22T18:26:01.048Z,1729621561.048 [NAL9602] Loaded 2024-10-22T18:26:01.049Z,1729621561.049 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-10-22T18:26:01.078Z,1729621561.078 [Onboard] Loaded 2024-10-22T18:26:01.078Z,1729621561.078 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-10-22T18:26:01.079Z,1729621561.079 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409214E0 2024-10-22T18:26:01.079Z,1729621561.079 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 916 2024-10-22T18:26:01.093Z,1729621561.093 [Power24vConverter] Loaded 2024-10-22T18:26:01.093Z,1729621561.093 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-10-22T18:26:01.109Z,1729621561.109 [Radio_Surface] Loaded 2024-10-22T18:26:01.110Z,1729621561.110 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-10-22T18:26:01.111Z,1729621561.111 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409514E0 2024-10-22T18:26:01.111Z,1729621561.111 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 917 2024-10-22T18:26:01.126Z,1729621561.126 [Sonardyne_Nano] Loaded 2024-10-22T18:26:01.126Z,1729621561.126 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2024-10-22T18:26:01.127Z,1729621561.127 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-10-22T18:26:01.129Z,1729621561.129 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-10-22T18:26:01.268Z,1729621561.268 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-10-22T18:26:01.268Z,1729621561.268 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-10-22T18:26:01.415Z,1729621561.415 [VerticalControl](DEBUG): Construct VerticalControl. 2024-10-22T18:26:01.474Z,1729621561.474 [VerticalControl] Loaded 2024-10-22T18:26:01.475Z,1729621561.475 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-10-22T18:26:01.477Z,1729621561.477 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-10-22T18:26:01.570Z,1729621561.570 [HorizontalControl] Loaded 2024-10-22T18:26:01.570Z,1729621561.570 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-10-22T18:26:01.573Z,1729621561.573 [SpeedControl](DEBUG): Construct SpeedControl. 2024-10-22T18:26:01.576Z,1729621561.576 [SpeedControl] Loaded 2024-10-22T18:26:01.576Z,1729621561.576 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-10-22T18:26:01.607Z,1729621561.607 [LoopControl](DEBUG): Construct LoopControl. 2024-10-22T18:26:01.607Z,1729621561.607 [LoopControl] Loaded 2024-10-22T18:26:01.608Z,1729621561.608 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-10-22T18:26:01.608Z,1729621561.608 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-10-22T18:26:01.609Z,1729621561.609 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-10-22T18:26:04.599Z,1729621564.599 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-10-22T18:26:04.601Z,1729621564.601 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-10-22T18:26:04.646Z,1729621564.646 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-10-22T18:26:04.647Z,1729621564.647 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-10-22T18:26:04.785Z,1729621564.785 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-10-22T18:26:04.786Z,1729621564.786 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-10-22T18:26:04.828Z,1729621564.828 [DeadReckonUsingSpeedCalculator] Loaded 2024-10-22T18:26:04.828Z,1729621564.828 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 2024-10-22T18:26:04.870Z,1729621564.870 [DeadReckonWithRespectToSeafloor] Loaded 2024-10-22T18:26:04.870Z,1729621564.870 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2024-10-22T18:26:04.883Z,1729621564.883 [NavChart] Loaded 2024-10-22T18:26:04.884Z,1729621564.884 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-10-22T18:26:04.889Z,1729621564.889 [UniversalFixResidualReporter] Loaded 2024-10-22T18:26:04.890Z,1729621564.890 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-10-22T18:26:04.890Z,1729621564.890 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-10-22T18:26:04.891Z,1729621564.891 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-10-22T18:26:05.061Z,1729621565.061 [SBIT](DEBUG): Construct Startup Built In Test. 2024-10-22T18:26:05.070Z,1729621565.070 [SBIT] Loaded 2024-10-22T18:26:05.070Z,1729621565.070 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-10-22T18:26:05.073Z,1729621565.073 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-10-22T18:26:05.086Z,1729621565.086 [IBIT] Loaded 2024-10-22T18:26:05.087Z,1729621565.087 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-10-22T18:26:05.092Z,1729621565.092 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-10-22T18:26:05.200Z,1729621565.200 [CBIT] Loaded 2024-10-22T18:26:05.201Z,1729621565.201 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-10-22T18:26:05.201Z,1729621565.201 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-10-22T18:26:05.202Z,1729621565.202 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-10-22T18:26:05.417Z,1729621565.417 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-10-22T18:26:05.418Z,1729621565.418 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-10-22T18:26:05.580Z,1729621565.580 [BuoyancyServo] Loaded 2024-10-22T18:26:05.580Z,1729621565.580 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-10-22T18:26:05.602Z,1729621565.602 [ElevatorServo] Loaded 2024-10-22T18:26:05.603Z,1729621565.603 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-10-22T18:26:05.624Z,1729621565.624 [MassServo] Loaded 2024-10-22T18:26:05.625Z,1729621565.625 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-10-22T18:26:05.645Z,1729621565.645 [RudderServo] Loaded 2024-10-22T18:26:05.646Z,1729621565.646 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-10-22T18:26:05.662Z,1729621565.662 [ThrusterHE] Loaded 2024-10-22T18:26:05.663Z,1729621565.663 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-10-22T18:26:05.663Z,1729621565.663 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-10-22T18:26:05.664Z,1729621565.664 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-10-22T18:26:05.889Z,1729621565.889 [CTD_Seabird] Loaded 2024-10-22T18:26:05.890Z,1729621565.890 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-10-22T18:26:05.891Z,1729621565.891 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B7B4E0 2024-10-22T18:26:05.891Z,1729621565.891 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 918 2024-10-22T18:26:05.926Z,1729621565.926 [ESPComponent] Loaded 2024-10-22T18:26:05.927Z,1729621565.927 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2024-10-22T18:26:05.949Z,1729621565.949 [PAR_Licor] Loaded 2024-10-22T18:26:05.949Z,1729621565.949 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-10-22T18:26:05.994Z,1729621565.994 [WetLabsBB2FL] Loaded 2024-10-22T18:26:05.994Z,1729621565.994 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-10-22T18:26:05.995Z,1729621565.995 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BAB4E0 2024-10-22T18:26:05.995Z,1729621565.995 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 919 2024-10-22T18:26:05.996Z,1729621565.996 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-10-22T18:26:05.997Z,1729621565.997 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-10-22T18:26:06.068Z,1729621566.068 [DepthRateCalculator] Loaded 2024-10-22T18:26:06.068Z,1729621566.068 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-10-22T18:26:06.073Z,1729621566.073 [PitchRateCalculator] Loaded 2024-10-22T18:26:06.073Z,1729621566.073 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-10-22T18:26:06.082Z,1729621566.082 [SpeedCalculator] Loaded 2024-10-22T18:26:06.082Z,1729621566.082 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-10-22T18:26:06.087Z,1729621566.087 [YawRateCalculator] Loaded 2024-10-22T18:26:06.087Z,1729621566.087 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-10-22T18:26:06.106Z,1729621566.106 [ElevatorOffsetCalculator] Loaded 2024-10-22T18:26:06.106Z,1729621566.106 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-10-22T18:26:06.107Z,1729621566.107 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-10-22T18:26:06.108Z,1729621566.108 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-10-22T18:26:06.191Z,1729621566.191 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-10-22T18:26:06.198Z,1729621566.198 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-10-22T18:26:06.200Z,1729621566.200 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-10-22T18:26:06.212Z,1729621566.212 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-10-22T18:26:06.213Z,1729621566.213 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C374E0 2024-10-22T18:26:06.214Z,1729621566.214 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 920 2024-10-22T18:26:06.218Z,1729621566.218 [Supervisor](INFO): Main Thread ID is 820 2024-10-22T18:26:06.218Z,1729621566.218 [Supervisor](DEBUG): Running supervisor. 2024-10-22T18:26:06.219Z,1729621566.219 [CommandExec ThreadHandler](INFO): Handler Thread ID is 921 2024-10-22T18:26:06.219Z,1729621566.219 [CommandExec](INFO): Initializing the command executive. 2024-10-22T18:26:06.221Z,1729621566.221 [CommandLine ThreadHandler](INFO): Handler Thread ID is 922 2024-10-22T18:26:06.223Z,1729621566.223 [controlThread ThreadHandler](INFO): Handler Thread ID is 923 2024-10-22T18:26:06.223Z,1729621566.223 [controlThread](DEBUG): Initializing ControlThread 2024-10-22T18:26:06.228Z,1729621566.228 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-10-22T18:26:06.229Z,1729621566.229 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-10-22T18:26:06.230Z,1729621566.230 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-10-22T18:26:06.230Z,1729621566.230 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-10-22T18:26:06.231Z,1729621566.231 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:26:06.232Z,1729621566.232 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-10-22T18:26:06.232Z,1729621566.232 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-10-22T18:26:06.232Z,1729621566.232 [SBIT](INFO): Initialize SBIT Component. 2024-10-22T18:26:06.233Z,1729621566.233 [SBIT](IMPORTANT): git: 2024-10-22_A 2024-10-22T18:26:06.233Z,1729621566.233 [SBIT](INFO): git hash: a2a4bcf12dfde36db5bd7890264b8f5dc391f296 2024-10-22T18:26:06.233Z,1729621566.233 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-10-22T18:26:06.235Z,1729621566.235 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2024-10-22T18:26:06.236Z,1729621566.236 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2024-10-22T18:26:06.236Z,1729621566.236 [IBIT](INFO): Initialize IBIT Component. 2024-10-22T18:26:06.237Z,1729621566.237 [CBIT](DEBUG): Initialize CBIT Component. 2024-10-22T18:26:06.238Z,1729621566.238 [logger ThreadHandler](INFO): Handler Thread ID is 924 2024-10-22T18:26:06.250Z,1729621566.250 [CBIT](DEBUG): Initialized mux pins. 2024-10-22T18:26:06.250Z,1729621566.250 [CBIT](DEBUG): Initializing the watchdog timer. 2024-10-22T18:26:06.258Z,1729621566.258 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 925 2024-10-22T18:26:06.270Z,1729621566.270 [DAT ThreadHandler](INFO): Handler Thread ID is 926 2024-10-22T18:26:06.271Z,1729621566.271 [DAT](INFO): Powering up 2024-10-22T18:26:06.271Z,1729621566.271 [DAT](DEBUG): Initializing DAT. 2024-10-22T18:26:06.274Z,1729621566.274 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-10-22T18:26:06.274Z,1729621566.274 [CBIT](DEBUG): Initializing heartbeat. 2024-10-22T18:26:06.276Z,1729621566.276 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 928 2024-10-22T18:26:06.278Z,1729621566.278 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-10-22T18:26:06.286Z,1729621566.286 [Onboard ThreadHandler](INFO): Handler Thread ID is 929 2024-10-22T18:26:06.304Z,1729621566.304 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 930 2024-10-22T18:26:06.318Z,1729621566.318 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 931 2024-10-22T18:26:06.319Z,1729621566.319 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-10-22T18:26:06.323Z,1729621566.323 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 933 2024-10-22T18:26:06.326Z,1729621566.326 [WetLabsBB2FL](INFO): Powering up 2024-10-22T18:26:06.327Z,1729621566.327 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 935 2024-10-22T18:26:06.337Z,1729621566.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 2024-10-22T18:26:06.337Z,1729621566.337 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 2024-10-22T18:26:06.337Z,1729621566.337 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 2024-10-22T18:26:06.338Z,1729621566.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 2024-10-22T18:26:06.339Z,1729621566.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 2024-10-22T18:26:06.339Z,1729621566.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 2024-10-22T18:26:06.346Z,1729621566.346 [CBIT](DEBUG): Deactivating GF circuits. 2024-10-22T18:26:06.346Z,1729621566.346 [CBIT](DEBUG): Deactivating emergency mode. 2024-10-22T18:26:06.386Z,1729621566.386 [CBIT](DEBUG): Backplane powered. 2024-10-22T18:26:06.390Z,1729621566.390 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-10-22T18:26:06.390Z,1729621566.390 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-10-22T18:26:06.391Z,1729621566.391 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-10-22T18:26:06.391Z,1729621566.391 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-10-22T18:26:06.391Z,1729621566.391 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-10-22T18:26:06.392Z,1729621566.392 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-10-22T18:26:06.393Z,1729621566.393 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-10-22T18:26:06.393Z,1729621566.393 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-10-22T18:26:06.398Z,1729621566.398 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-10-22T18:26:06.446Z,1729621566.446 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-10-22T18:26:06.450Z,1729621566.450 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-22T18:26:06.482Z,1729621566.482 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2024-10-22T18:26:06.483Z,1729621566.483 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-10-22T18:26:06.483Z,1729621566.483 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-10-22T18:26:06.485Z,1729621566.485 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-10-22T18:26:06.678Z,1729621566.678 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2024-10-22T18:26:06.684Z,1729621566.684 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-10-22T18:26:06.685Z,1729621566.685 [Default:A.Wait](DEBUG): Construct Wait. 2024-10-22T18:26:06.700Z,1729621566.700 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-10-22T18:26:06.718Z,1729621566.718 [Radio_Surface](INFO): Powering up 2024-10-22T18:26:06.724Z,1729621566.724 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-10-22T18:26:06.739Z,1729621566.739 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-10-22T18:26:06.757Z,1729621566.757 [Default:E.Execute](DEBUG): Construct Execute. 2024-10-22T18:26:06.760Z,1729621566.760 [MissionManager](DEBUG): # Copyright (c) 2024 MBARI # MBARI Proprietary Information. Confidential. All Rights Reserved # Unauthorized copying or distribution of this file via any medium is strictly # prohibited. # # WARNING - This file contains information whose export is restricted by the # Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as # amended. Violations of these export laws are subject to severe civil and/or # criminal penalties. mission Default { arguments { ElapsedSinceDefaultStarted = 0 minute } behavior Guidance:Wait { """ Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. """ run in sequence set duration = 13 second } behavior Guidance:GoToSurface { run in progression } aggregate CheckIn { run in sequence repeat=288 readDatum id="Read_GPS" { timeout duration=P5M Universal:time_fix } readDatum id="Read_Iridium" { timeout duration=P4H { behavior Guidance:Execute { run in sequence set command = "Burn on" } syslog critical "Dropped weight due to communications timeout." } Universal:platform_communications } behavior Guidance:Wait { run in sequence set duration = 5 minute } assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started ) syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute } syslog important "Restarting logs and Default mission." behavior Guidance:Execute { run in sequence set command = "restart logs" } } 2024-10-22T18:26:06.765Z,1729621566.765 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2024-10-22T18:26:06.774Z,1729621566.774 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-10-22T18:26:06.910Z,1729621566.910 [Power24vConverter](INFO): Powering up. 2024-10-22T18:26:06.911Z,1729621566.911 [Sonardyne_Nano](INFO): Initializing. 2024-10-22T18:26:06.964Z,1729621566.964 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-10-22T18:26:06.970Z,1729621566.970 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-10-22T18:26:06.971Z,1729621566.971 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-10-22T18:26:06.978Z,1729621566.978 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-10-22T18:26:06.979Z,1729621566.979 [MassServo](DEBUG): Initializing EZServoServo. 2024-10-22T18:26:06.986Z,1729621566.986 [MassServo](DEBUG): Initializing MassServo. 2024-10-22T18:26:06.987Z,1729621566.987 [RudderServo](DEBUG): Initializing EZServoServo. 2024-10-22T18:26:06.994Z,1729621566.994 [RudderServo](DEBUG): Initializing RudderServo. 2024-10-22T18:26:06.995Z,1729621566.995 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-10-22T18:26:07.002Z,1729621567.002 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-10-22T18:26:08.434Z,1729621568.434 [WetLabsBB2FL](INFO): Powering down 2024-10-22T18:26:09.196Z,1729621569.196 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2024-10-22T18:26:10.448Z,1729621570.448 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409 2024-10-22T18:26:12.491Z,1729621572.491 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-10-22T18:26:18.618Z,1729621578.618 [DAT](INFO): DAT read: 2024-10-22T18:26:18.619Z,1729621578.619 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-10-22T18:26:20.131Z,1729621580.131 [DAT](INFO): DAT read: MF Frequency Band 2024-10-22T18:26:20.132Z,1729621580.132 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-10-22T18:26:20.132Z,1729621580.132 [DAT](INFO): DAT read: Oct 22 2024 18:25:05 2024-10-22T18:26:21.139Z,1729621581.139 [DAT](INFO): DAT read: Features enabled [Bearing] 2024-10-22T18:26:21.140Z,1729621581.140 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2024-10-22T18:26:21.140Z,1729621581.140 [DAT](INFO): commRate: 800 2024-10-22T18:26:23.206Z,1729621583.206 [DAT](INFO): entering command mode 2024-10-22T18:26:23.406Z,1729621583.406 [DAT](INFO): DAT read: 2024-10-22T18:26:23.407Z,1729621583.407 [DAT](INFO): DAT read: user:1> 2024-10-22T18:26:23.407Z,1729621583.407 [DAT](INFO): setting verbose to 3 2024-10-22T18:26:23.661Z,1729621583.661 [DAT](INFO): DAT read: user:1> 2024-10-22T18:26:23.663Z,1729621583.663 [DAT](INFO): DAT read: Verbose | 3 2024-10-22T18:26:23.663Z,1729621583.663 [DAT](INFO): set verbose to 3 2024-10-22T18:26:23.663Z,1729621583.663 [DAT](INFO): setting DatVerbose to 27440 2024-10-22T18:26:23.910Z,1729621583.910 [DAT](INFO): DAT read: user:2> 2024-10-22T18:26:23.911Z,1729621583.911 [DAT](INFO): DAT read: DatVerbose | 27440 2024-10-22T18:26:23.912Z,1729621583.912 [DAT](INFO): set DatVerbose to 27440 2024-10-22T18:26:23.912Z,1729621583.912 [DAT](INFO): setting transmit power to 8 2024-10-22T18:26:24.162Z,1729621584.162 [DAT](INFO): DAT read: user:3> 2024-10-22T18:26:24.163Z,1729621584.163 [DAT](INFO): DAT read: TxPower | 8 (Max) 2024-10-22T18:26:24.163Z,1729621584.163 [DAT](INFO): set transmit power to 8 2024-10-22T18:26:24.164Z,1729621584.164 [DAT](INFO): setting local address to 2 2024-10-22T18:26:24.414Z,1729621584.414 [DAT](INFO): DAT read: user:4> 2024-10-22T18:26:24.415Z,1729621584.415 [DAT](INFO): DAT read: LocalAddr | 2 2024-10-22T18:26:24.415Z,1729621584.415 [DAT](INFO): set local address to 2 2024-10-22T18:26:24.417Z,1729621584.417 [DAT](INFO): Setting time to: 18:26:24 And date to:10/22/2024 2024-10-22T18:26:24.666Z,1729621584.666 [DAT](INFO): DAT read: user:5> 2024-10-22T18:26:24.667Z,1729621584.667 [DAT](INFO): DAT read: Tue Oct 22, 2024 18:26:24 2024-10-22T18:26:24.668Z,1729621584.668 [DAT](INFO): Local DAT time set to Tue Oct 22, 2024 18:26:24 2024-10-22T18:26:26.993Z,1729621586.993 [SBIT](IMPORTANT): Beginning Startup BIT 2024-10-22T18:26:26.997Z,1729621586.997 [CBIT](IMPORTANT): Beginning ground fault scan 2024-10-22T18:26:31.424Z,1729621591.424 [NAL9602](INFO): Powering up NAL9602 2024-10-22T18:26:37.938Z,1729621597.938 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.005360 CHAN A1 (24V): -0.000527 CHAN A2 (12V): 0.000134 CHAN A3 (5V): -0.001801 CHAN B0 (3.3V): -0.000723 CHAN B1 (3.15aV): -0.001361 CHAN B2 (3.15bV): -0.001449 CHAN B3 (GND): -0.002464 OPEN: 0.004540 Full Scale: +/- 1 mA 2024-10-22T18:26:42.331Z,1729621602.331 [NAL9602](INFO): NAL9602 initialized 2024-10-22T18:27:13.430Z,1729621633.430 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:27:21.129Z,1729621641.129 [SBIT](IMPORTANT): SBIT PASSED 2024-10-22T18:27:21.131Z,1729621641.131 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-10-22T18:27:21.131Z,1729621641.131 [SBIT](IMPORTANT): 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): BackseatComponent.loadAtStartup=1 bool; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): CBIT.gf24Offset=162 microampere; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): DATMMP.loadAtStartup=0 bool; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=-0.1 meter; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute; 2024-10-22T18:27:21.132Z,1729621641.132 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool; 2024-10-22T18:27:21.549Z,1729621641.549 [MissionManager](IMPORTANT): Started mission Startup 2024-10-22T18:27:21.549Z,1729621641.549 [Startup] Running Loop=1 2024-10-22T18:27:21.549Z,1729621641.549 [Startup](DEBUG): Aggregate::initialize Startup 2024-10-22T18:27:21.549Z,1729621641.549 [Startup:A.GoToSurface] Running Loop=1 2024-10-22T18:27:21.550Z,1729621641.550 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-22T18:27:21.550Z,1729621641.550 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-22T18:27:21.551Z,1729621641.551 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-22T18:27:21.551Z,1729621641.551 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-22T18:27:21.551Z,1729621641.551 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-22T18:27:21.552Z,1729621641.552 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-22T18:27:21.552Z,1729621641.552 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-22T18:27:21.554Z,1729621641.554 [Startup:StartupSatComms] Running Loop=1 2024-10-22T18:27:21.554Z,1729621641.554 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-10-22T18:27:21.554Z,1729621641.554 [Startup:StartupSatComms:A] Running Loop=1 2024-10-22T18:27:21.944Z,1729621641.944 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-10-22T18:27:27.172Z,1729621647.172 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:27:38.239Z,1729621658.239 [CommandExec](IMPORTANT): got command strobe off 2024-10-22T18:27:38.239Z,1729621658.239 [CommandExec](IMPORTANT): Deactivating strobe 2024-10-22T18:27:41.307Z,1729621661.307 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:27:55.851Z,1729621675.851 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:28:04.000Z,1729621684.000 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004720 2024-10-22T18:28:10.004Z,1729621690.004 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:28:21.742Z,1729621701.742 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-22T18:27:21.6Z 2024-10-22T18:28:21.742Z,1729621701.742 [Startup:StartupSatComms:A] Stopped 2024-10-22T18:28:21.742Z,1729621701.742 [Startup:StartupSatComms:B] Running Loop=1 2024-10-22T18:28:22.141Z,1729621702.141 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-10-22T18:28:23.740Z,1729621703.740 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:28:30.963Z,1729621710.963 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241022T181453/Courier0007.lzma 2024-10-22T18:28:31.964Z,1729621711.964 [DataOverHttps](INFO): Moved sent file to Logs/20241022T181453/Courier0007.lzma.bak 2024-10-22T18:28:31.964Z,1729621711.964 [DataOverHttps](INFO): SBD MOMSN=23327040 2024-10-22T18:28:37.477Z,1729621717.477 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:28:47.501Z,1729621727.501 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241022T182544/Courier0000.lzma 2024-10-22T18:28:49.033Z,1729621729.033 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Courier0000.lzma.bak 2024-10-22T18:28:49.033Z,1729621729.033 [DataOverHttps](INFO): SBD MOMSN=23327042 2024-10-22T18:28:51.626Z,1729621731.626 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7). 2024-10-22T18:29:05.813Z,1729621745.813 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-10-22T18:29:06.260Z,1729621746.260 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-10-22T18:29:06.260Z,1729621746.260 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:29:06.261Z,1729621746.261 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2024-10-22T18:29:06.261Z,1729621746.261 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:29:06.263Z,1729621746.263 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2024-10-22T18:29:06.263Z,1729621746.263 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:29:06.273Z,1729621746.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:29:06.274Z,1729621746.274 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:29:06.274Z,1729621746.274 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:29:06.640Z,1729621746.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:29:06.640Z,1729621746.640 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-10-22T18:29:06.641Z,1729621746.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:29:06.641Z,1729621746.641 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2024-10-22T18:29:06.642Z,1729621746.642 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:29:06.642Z,1729621746.642 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2024-10-22T18:29:07.010Z,1729621747.010 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:29:15.400Z,1729621755.400 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20241022T181453/Express0005.lzma 2024-10-22T18:29:16.419Z,1729621756.419 [DataOverHttps](INFO): Moved sent file to Logs/20241022T181453/Express0005.lzma.bak 2024-10-22T18:29:16.420Z,1729621756.420 [DataOverHttps](INFO): SBD MOMSN=23327045 2024-10-22T18:29:21.953Z,1729621761.953 [Startup:StartupSatComms:B](INFO): Timed out from 2024-10-22T18:28:21.7Z 2024-10-22T18:29:21.953Z,1729621761.953 [Startup:StartupSatComms:B] Stopped 2024-10-22T18:29:21.953Z,1729621761.953 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-10-22T18:29:21.953Z,1729621761.953 [Startup:StartupSatComms] Stopped 2024-10-22T18:29:21.953Z,1729621761.953 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-10-22T18:29:21.958Z,1729621761.958 [Startup](INFO): Completed Startup 2024-10-22T18:29:21.958Z,1729621761.958 [MissionManager](INFO): Startup is completed. 2024-10-22T18:29:21.958Z,1729621761.958 [MissionManager](INFO): Uninitializing Mission Startup 2024-10-22T18:29:21.958Z,1729621761.958 [Startup] Stopped 2024-10-22T18:29:21.959Z,1729621761.959 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-10-22T18:29:21.959Z,1729621761.959 [Startup:A.GoToSurface] Stopped 2024-10-22T18:29:21.959Z,1729621761.959 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-10-22T18:29:22.356Z,1729621762.356 [MissionManager](IMPORTANT): Started mission Default 2024-10-22T18:29:22.356Z,1729621762.356 [Default] Running Loop=1 2024-10-22T18:29:22.356Z,1729621762.356 [Default](DEBUG): Aggregate::initialize Default 2024-10-22T18:29:22.356Z,1729621762.356 [Default:B.GoToSurface] Running Loop=1 2024-10-22T18:29:22.357Z,1729621762.357 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-10-22T18:29:22.357Z,1729621762.357 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-10-22T18:29:22.357Z,1729621762.357 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-10-22T18:29:22.362Z,1729621762.362 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-10-22T18:29:22.362Z,1729621762.362 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-10-22T18:29:22.362Z,1729621762.362 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-10-22T18:29:22.363Z,1729621762.363 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-10-22T18:29:22.363Z,1729621762.363 [Default:A.Wait] Running Loop=1 2024-10-22T18:29:22.363Z,1729621762.363 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-10-22T18:29:34.196Z,1729621774.196 [DataOverHttps](INFO): Sending 99 bytes from file Logs/20241022T181453/Express0008.lzma 2024-10-22T18:29:35.195Z,1729621775.195 [DataOverHttps](INFO): Moved sent file to Logs/20241022T181453/Express0008.lzma.bak 2024-10-22T18:29:35.196Z,1729621775.196 [DataOverHttps](INFO): SBD MOMSN=23327049 2024-10-22T18:29:35.670Z,1729621775.670 [Default:A.Wait](INFO): Done Waiting. 2024-10-22T18:29:35.670Z,1729621775.670 [Default:A.Wait] Stopped 2024-10-22T18:29:35.670Z,1729621775.670 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-10-22T18:29:36.079Z,1729621776.079 [Default:CheckIn] Running Loop=1 2024-10-22T18:29:36.079Z,1729621776.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-22T18:29:36.079Z,1729621776.079 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-22T18:29:36.506Z,1729621776.506 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-10-22T18:30:33.934Z,1729621833.934 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-10-22T18:31:45.392Z,1729621905.392 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-10-22T18:32:07.231Z,1729621927.231 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2024-10-22T18:32:07.231Z,1729621927.231 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:32:07.232Z,1729621927.232 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2024-10-22T18:32:07.232Z,1729621927.232 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:32:07.233Z,1729621927.233 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2024-10-22T18:32:07.233Z,1729621927.233 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:32:07.244Z,1729621927.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:32:07.244Z,1729621927.244 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:32:07.244Z,1729621927.244 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:32:07.655Z,1729621927.655 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:32:07.655Z,1729621927.655 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2024-10-22T18:32:07.656Z,1729621927.656 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:32:07.656Z,1729621927.656 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2024-10-22T18:32:07.656Z,1729621927.656 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:32:07.656Z,1729621927.656 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2024-10-22T18:32:08.022Z,1729621928.022 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:32:31.096Z,1729621951.096 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-10-22T18:32:50.488Z,1729621970.488 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-10-22T18:34:11.639Z,1729622051.639 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0. 2024-10-22T18:34:11.640Z,1729622051.640 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 6. 2024-10-22T18:34:11.643Z,1729622051.643 [BPC1](INFO): Received data from all battery sticks. 2024-10-22T18:34:36.321Z,1729622076.321 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-22T18:29:36.1Z 2024-10-22T18:34:36.321Z,1729622076.321 [Default:CheckIn:Read_GPS] Stopped 2024-10-22T18:34:36.321Z,1729622076.321 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-22T18:34:36.702Z,1729622076.702 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-10-22T18:34:38.431Z,1729622078.431 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20241022T182544/Courier0004.lzma 2024-10-22T18:34:38.655Z,1729622078.655 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Courier0004.lzma.bak 2024-10-22T18:34:38.655Z,1729622078.655 [DataOverHttps](INFO): SBD MOMSN=23327052 2024-10-22T18:34:55.659Z,1729622095.659 [DataOverHttps](INFO): Sending 859 bytes from file Logs/20241022T182544/Express0001.lzma 2024-10-22T18:34:56.660Z,1729622096.660 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Express0001.lzma.bak 2024-10-22T18:34:56.660Z,1729622096.660 [DataOverHttps](INFO): SBD MOMSN=23327054 2024-10-22T18:35:08.221Z,1729622108.221 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2024-10-22T18:35:08.221Z,1729622108.221 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:35:08.244Z,1729622108.244 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2024-10-22T18:35:08.244Z,1729622108.244 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:35:08.245Z,1729622108.245 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 2024-10-22T18:35:08.245Z,1729622108.245 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:35:08.266Z,1729622108.266 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:35:08.267Z,1729622108.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:35:08.267Z,1729622108.267 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:35:08.646Z,1729622108.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:35:08.646Z,1729622108.646 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2024-10-22T18:35:08.646Z,1729622108.646 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:35:08.646Z,1729622108.646 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2024-10-22T18:35:08.647Z,1729622108.647 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:35:08.647Z,1729622108.647 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 2024-10-22T18:35:09.019Z,1729622109.019 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:35:13.762Z,1729622113.762 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20241022T182544/Express0005.lzma 2024-10-22T18:35:14.763Z,1729622114.763 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Express0005.lzma.bak 2024-10-22T18:35:14.764Z,1729622114.764 [DataOverHttps](INFO): SBD MOMSN=23327079 2024-10-22T18:35:17.548Z,1729622117.548 [Default:CheckIn:Read_Iridium] Stopped 2024-10-22T18:35:17.548Z,1729622117.548 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-22T18:35:17.548Z,1729622117.548 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-22T18:36:44.792Z,1729622204.792 [NAL9602](FAULT): GPS failed to acquire within timeout. 2024-10-22T18:36:44.792Z,1729622204.792 [NAL9602] Data Fault, FailCount= 1 2024-10-22T18:36:44.792Z,1729622204.792 [NAL9602](ERROR): Data Fault 2024-10-22T18:36:44.811Z,1729622204.811 [CBIT](ERROR): Data Fault in component: NAL9602 2024-10-22T18:36:45.191Z,1729622205.191 [NAL9602](INFO): Powering down 2024-10-22T18:36:46.017Z,1729622206.017 [CBIT](INFO): Clearing failed state for component NAL9602 2024-10-22T18:36:46.017Z,1729622206.017 [NAL9602] No Fault, FailCount= 1 2024-10-22T18:37:15.495Z,1729622235.495 [NAL9602](INFO): Powering up NAL9602 2024-10-22T18:37:26.404Z,1729622246.404 [NAL9602](INFO): NAL9602 initialized 2024-10-22T18:38:09.255Z,1729622289.255 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2024-10-22T18:38:09.255Z,1729622289.255 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:38:09.256Z,1729622289.256 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2024-10-22T18:38:09.256Z,1729622289.256 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:38:09.257Z,1729622289.257 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4 2024-10-22T18:38:09.257Z,1729622289.257 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:38:09.288Z,1729622289.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:38:09.288Z,1729622289.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:38:09.288Z,1729622289.288 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:38:09.668Z,1729622289.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:38:09.668Z,1729622289.668 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2024-10-22T18:38:09.668Z,1729622289.668 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:38:09.668Z,1729622289.668 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2024-10-22T18:38:09.669Z,1729622289.669 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:38:09.669Z,1729622289.669 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4 2024-10-22T18:38:10.042Z,1729622290.042 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:40:18.119Z,1729622418.119 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-10-22T18:40:18.119Z,1729622418.119 [Default:CheckIn:C.Wait] Stopped 2024-10-22T18:40:18.119Z,1729622418.119 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-10-22T18:40:18.119Z,1729622418.119 [Default:CheckIn:D] Running Loop=1 2024-10-22T18:40:18.540Z,1729622418.540 [Default:CheckIn:D] Stopped 2024-10-22T18:40:18.540Z,1729622418.540 [Default:CheckIn:E] Running Loop=1 2024-10-22T18:40:18.950Z,1729622418.950 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.936398 min 2024-10-22T18:40:18.950Z,1729622418.950 [Default:CheckIn:E] Stopped 2024-10-22T18:40:18.950Z,1729622418.950 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-10-22T18:40:18.950Z,1729622418.950 [Default:CheckIn] Stopped 2024-10-22T18:40:18.950Z,1729622418.950 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-10-22T18:40:18.951Z,1729622418.951 [Default:CheckIn](INFO): Running loop #2 2024-10-22T18:40:18.951Z,1729622418.951 [Default:CheckIn] Running Loop=2 2024-10-22T18:40:18.951Z,1729622418.951 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-10-22T18:40:18.951Z,1729622418.951 [Default:CheckIn:Read_GPS] Running Loop=1 2024-10-22T18:41:10.275Z,1729622470.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2024-10-22T18:41:10.275Z,1729622470.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:41:10.276Z,1729622470.276 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2024-10-22T18:41:10.276Z,1729622470.276 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:41:10.277Z,1729622470.277 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 5 2024-10-22T18:41:10.277Z,1729622470.277 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:41:10.296Z,1729622470.296 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:41:10.296Z,1729622470.296 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:41:10.297Z,1729622470.297 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:41:10.709Z,1729622470.709 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:41:10.709Z,1729622470.709 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2024-10-22T18:41:10.710Z,1729622470.710 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:41:10.710Z,1729622470.710 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2024-10-22T18:41:10.710Z,1729622470.710 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:41:10.710Z,1729622470.710 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 5 2024-10-22T18:41:11.067Z,1729622471.067 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:44:11.254Z,1729622651.254 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2024-10-22T18:44:11.254Z,1729622651.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:44:11.256Z,1729622651.256 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2024-10-22T18:44:11.256Z,1729622651.256 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:44:11.257Z,1729622651.257 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 6 2024-10-22T18:44:11.257Z,1729622651.257 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:44:11.267Z,1729622651.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:44:11.267Z,1729622651.267 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:44:11.268Z,1729622651.268 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:44:11.693Z,1729622651.693 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:44:11.694Z,1729622651.694 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2024-10-22T18:44:11.694Z,1729622651.694 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:44:11.694Z,1729622651.694 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2024-10-22T18:44:11.695Z,1729622651.695 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:44:11.695Z,1729622651.695 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 6 2024-10-22T18:44:12.082Z,1729622652.082 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2024-10-22T18:45:19.135Z,1729622719.135 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-22T18:40:18.0Z 2024-10-22T18:45:19.135Z,1729622719.135 [Default:CheckIn:Read_GPS] Stopped 2024-10-22T18:45:19.135Z,1729622719.135 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-10-22T18:45:26.413Z,1729622726.413 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20241022T182544/Courier0007.lzma 2024-10-22T18:45:27.415Z,1729622727.415 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Courier0007.lzma.bak 2024-10-22T18:45:27.416Z,1729622727.416 [DataOverHttps](INFO): SBD MOMSN=23327110 2024-10-22T18:45:44.482Z,1729622744.482 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20241022T182544/Express0008.lzma 2024-10-22T18:45:45.483Z,1729622745.483 [DataOverHttps](INFO): Moved sent file to Logs/20241022T182544/Express0008.lzma.bak 2024-10-22T18:45:45.484Z,1729622745.484 [DataOverHttps](INFO): SBD MOMSN=23327114 2024-10-22T18:45:48.240Z,1729622748.240 [Default:CheckIn:Read_Iridium] Stopped 2024-10-22T18:45:48.240Z,1729622748.240 [Default:CheckIn:C.Wait] Running Loop=1 2024-10-22T18:45:48.240Z,1729622748.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-10-22T18:47:12.246Z,1729622832.246 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2024-10-22T18:47:12.246Z,1729622832.246 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-10-22T18:47:12.247Z,1729622832.247 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2024-10-22T18:47:12.247Z,1729622832.247 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2024-10-22T18:47:12.248Z,1729622832.248 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 7 2024-10-22T18:47:12.248Z,1729622832.248 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2024-10-22T18:47:12.300Z,1729622832.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-10-22T18:47:12.300Z,1729622832.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2024-10-22T18:47:12.301Z,1729622832.301 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2024-10-22T18:47:12.732Z,1729622832.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-10-22T18:47:12.732Z,1729622832.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2024-10-22T18:47:12.732Z,1729622832.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2024-10-22T18:47:12.732Z,1729622832.732 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2024-10-22T18:47:12.733Z,1729622832.733 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2024-10-22T18:47