2024-09-20T16:11:17.024Z,1726848677.024 [Supervisor](DEBUG): Initializing supervisor. 2024-09-20T16:11:17.029Z,1726848677.029 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2024-09-20T16:11:17.030Z,1726848677.030 [SyncHandler](INFO): Protected caller Thread ID is 814 2024-09-20T16:11:17.030Z,1726848677.030 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2024-09-20T16:11:17.031Z,1726848677.031 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2024-09-20T16:11:17.032Z,1726848677.032 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 816 2024-09-20T16:11:17.036Z,1726848677.036 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2024-09-20T16:11:17.053Z,1726848677.053 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2024-09-20T16:11:17.054Z,1726848677.054 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2024-09-20T16:11:17.054Z,1726848677.054 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 817 2024-09-20T16:11:17.059Z,1726848677.059 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2024-09-20T16:11:17.060Z,1726848677.060 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2024-09-20T16:11:17.060Z,1726848677.060 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 818 2024-09-20T16:11:17.062Z,1726848677.062 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2024-09-20T16:11:17.063Z,1726848677.063 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2024-09-20T16:11:17.063Z,1726848677.063 [logger ThreadHandler](INFO): Protected caller Thread ID is 819 2024-09-20T16:11:17.067Z,1726848677.067 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2024-09-20T16:11:17.067Z,1726848677.067 [Supervisor](INFO): Looking for Config files in directory: Config/ 2024-09-20T16:11:17.069Z,1726848677.069 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2024-09-20T16:11:17.349Z,1726848677.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2024-09-20T16:11:17.350Z,1726848677.350 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2024-09-20T16:11:17.444Z,1726848677.444 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2024-09-20T16:11:18.002Z,1726848678.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2024-09-20T16:11:18.003Z,1726848678.003 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2024-09-20T16:11:18.369Z,1726848678.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2024-09-20T16:11:18.370Z,1726848678.370 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2024-09-20T16:11:18.479Z,1726848678.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2024-09-20T16:11:18.480Z,1726848678.480 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2024-09-20T16:11:18.909Z,1726848678.909 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2024-09-20T16:11:18.910Z,1726848678.910 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2024-09-20T16:11:19.140Z,1726848679.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2024-09-20T16:11:19.140Z,1726848679.140 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2024-09-20T16:11:19.696Z,1726848679.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2024-09-20T16:11:19.697Z,1726848679.697 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2024-09-20T16:11:19.856Z,1726848679.856 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2024-09-20T16:11:19.857Z,1726848679.857 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2024-09-20T16:11:19.949Z,1726848679.949 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2024-09-20T16:11:21.428Z,1726848681.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2024-09-20T16:11:21.428Z,1726848681.428 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2024-09-20T16:11:21.833Z,1726848681.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2024-09-20T16:11:21.833Z,1726848681.833 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2024-09-20T16:11:22.044Z,1726848682.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2024-09-20T16:11:22.045Z,1726848682.045 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2024-09-20T16:11:22.169Z,1726848682.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2024-09-20T16:11:22.170Z,1726848682.170 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2024-09-20T16:11:22.413Z,1726848682.413 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2024-09-20T16:11:22.413Z,1726848682.413 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2024-09-20T16:11:22.681Z,1726848682.681 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2024-09-20T16:11:22.683Z,1726848682.683 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/ 2024-09-20T16:11:22.684Z,1726848682.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg 2024-09-20T16:11:22.788Z,1726848682.788 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg 2024-09-20T16:11:22.887Z,1726848682.887 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg 2024-09-20T16:11:23.002Z,1726848683.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg 2024-09-20T16:11:23.098Z,1726848683.098 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg 2024-09-20T16:11:23.214Z,1726848683.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg 2024-09-20T16:11:23.320Z,1726848683.320 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg 2024-09-20T16:11:23.451Z,1726848683.451 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg 2024-09-20T16:11:23.621Z,1726848683.621 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg 2024-09-20T16:11:23.786Z,1726848683.786 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg 2024-09-20T16:11:23.909Z,1726848683.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg 2024-09-20T16:11:24.184Z,1726848684.184 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2024-09-20T16:11:24.185Z,1726848684.185 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/ 2024-09-20T16:11:24.185Z,1726848684.185 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2024-09-20T16:11:24.195Z,1726848684.195 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2024-09-20T16:11:24.248Z,1726848684.248 [VerticalControl](DEBUG): Construct VerticalControl. 2024-09-20T16:11:24.307Z,1726848684.307 [VerticalControl] Loaded 2024-09-20T16:11:24.307Z,1726848684.307 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2024-09-20T16:11:24.310Z,1726848684.310 [HorizontalControl](DEBUG): Construct HorizontalControl. 2024-09-20T16:11:24.355Z,1726848684.355 [HorizontalControl] Loaded 2024-09-20T16:11:24.356Z,1726848684.356 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2024-09-20T16:11:24.358Z,1726848684.358 [SpeedControl](DEBUG): Construct SpeedControl. 2024-09-20T16:11:24.361Z,1726848684.361 [SpeedControl] Loaded 2024-09-20T16:11:24.361Z,1726848684.361 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2024-09-20T16:11:24.364Z,1726848684.364 [LoopControl](DEBUG): Construct LoopControl. 2024-09-20T16:11:24.364Z,1726848684.364 [LoopControl] Loaded 2024-09-20T16:11:24.365Z,1726848684.365 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2024-09-20T16:11:24.365Z,1726848684.365 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2024-09-20T16:11:24.366Z,1726848684.366 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2024-09-20T16:11:24.375Z,1726848684.375 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2024-09-20T16:11:24.376Z,1726848684.376 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2024-09-20T16:11:24.544Z,1726848684.544 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2024-09-20T16:11:24.544Z,1726848684.544 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2024-09-20T16:11:24.699Z,1726848684.699 [BuoyancyServo] Loaded 2024-09-20T16:11:24.699Z,1726848684.699 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2024-09-20T16:11:24.721Z,1726848684.721 [ElevatorServo] Loaded 2024-09-20T16:11:24.721Z,1726848684.721 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2024-09-20T16:11:24.742Z,1726848684.742 [MassServo] Loaded 2024-09-20T16:11:24.742Z,1726848684.742 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2024-09-20T16:11:24.762Z,1726848684.762 [RudderServo] Loaded 2024-09-20T16:11:24.762Z,1726848684.762 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2024-09-20T16:11:24.778Z,1726848684.778 [ThrusterHE] Loaded 2024-09-20T16:11:24.778Z,1726848684.778 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2024-09-20T16:11:24.779Z,1726848684.779 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2024-09-20T16:11:24.779Z,1726848684.779 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2024-09-20T16:11:24.890Z,1726848684.890 [DeadReckonUsingMultipleVelocitySources] Loaded 2024-09-20T16:11:24.890Z,1726848684.890 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2024-09-20T16:11:24.903Z,1726848684.903 [NavChart] Loaded 2024-09-20T16:11:24.904Z,1726848684.904 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2024-09-20T16:11:24.909Z,1726848684.909 [UniversalFixResidualReporter] Loaded 2024-09-20T16:11:24.909Z,1726848684.909 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2024-09-20T16:11:24.910Z,1726848684.910 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2024-09-20T16:11:24.910Z,1726848684.910 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2024-09-20T16:11:24.979Z,1726848684.979 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2024-09-20T16:11:24.979Z,1726848684.979 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2024-09-20T16:11:25.432Z,1726848685.432 [AHRS_M2] Loaded 2024-09-20T16:11:25.432Z,1726848685.432 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2024-09-20T16:11:26.245Z,1726848686.245 [BPC1] Loaded 2024-09-20T16:11:26.245Z,1726848686.245 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2024-09-20T16:11:26.337Z,1726848686.337 [DATMMP] Loaded 2024-09-20T16:11:26.376Z,1726848686.376 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread. 2024-09-20T16:11:26.377Z,1726848686.377 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 4094C4E0 2024-09-20T16:11:26.377Z,1726848686.377 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 904 2024-09-20T16:11:26.803Z,1726848686.803 [DataOverHttps] Loaded 2024-09-20T16:11:26.803Z,1726848686.803 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2024-09-20T16:11:26.813Z,1726848686.813 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097C4E0 2024-09-20T16:11:26.813Z,1726848686.813 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 905 2024-09-20T16:11:26.895Z,1726848686.895 [Depth_Keller] Loaded 2024-09-20T16:11:26.896Z,1726848686.896 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2024-09-20T16:11:27.095Z,1726848687.095 [DropWeight] Loaded 2024-09-20T16:11:27.095Z,1726848687.095 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2024-09-20T16:11:27.313Z,1726848687.313 [NAL9602] Loaded 2024-09-20T16:11:27.314Z,1726848687.314 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2024-09-20T16:11:27.343Z,1726848687.343 [Onboard] Loaded 2024-09-20T16:11:27.344Z,1726848687.344 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2024-09-20T16:11:27.345Z,1726848687.345 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AC4E0 2024-09-20T16:11:27.345Z,1726848687.345 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 906 2024-09-20T16:11:27.351Z,1726848687.351 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1 2024-09-20T16:11:27.364Z,1726848687.364 [PowerOnly] Loaded 2024-09-20T16:11:27.364Z,1726848687.364 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2024-09-20T16:11:27.378Z,1726848687.378 [Power24vConverter] Loaded 2024-09-20T16:11:27.378Z,1726848687.378 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2024-09-20T16:11:27.399Z,1726848687.399 [Radio_Surface] Loaded 2024-09-20T16:11:27.399Z,1726848687.399 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2024-09-20T16:11:27.400Z,1726848687.400 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DC4E0 2024-09-20T16:11:27.401Z,1726848687.401 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 907 2024-09-20T16:11:27.402Z,1726848687.402 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2024-09-20T16:11:27.402Z,1726848687.402 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2024-09-20T16:11:27.451Z,1726848687.451 [DepthRateCalculator] Loaded 2024-09-20T16:11:27.451Z,1726848687.451 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2024-09-20T16:11:27.456Z,1726848687.456 [PitchRateCalculator] Loaded 2024-09-20T16:11:27.456Z,1726848687.456 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2024-09-20T16:11:27.465Z,1726848687.465 [SpeedCalculator] Loaded 2024-09-20T16:11:27.465Z,1726848687.465 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2024-09-20T16:11:27.469Z,1726848687.469 [YawRateCalculator] Loaded 2024-09-20T16:11:27.469Z,1726848687.469 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2024-09-20T16:11:27.488Z,1726848687.488 [ElevatorOffsetCalculator] Loaded 2024-09-20T16:11:27.488Z,1726848687.488 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2024-09-20T16:11:27.489Z,1726848687.489 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2024-09-20T16:11:27.489Z,1726848687.489 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2024-09-20T16:11:27.623Z,1726848687.623 [CANONSampler] Loaded 2024-09-20T16:11:27.623Z,1726848687.623 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread. 2024-09-20T16:11:27.687Z,1726848687.687 [CTD_Seabird] Loaded 2024-09-20T16:11:27.687Z,1726848687.687 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2024-09-20T16:11:27.688Z,1726848687.688 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A984E0 2024-09-20T16:11:27.688Z,1726848687.688 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 908 2024-09-20T16:11:27.708Z,1726848687.708 [PAR_Licor] Loaded 2024-09-20T16:11:27.708Z,1726848687.708 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2024-09-20T16:11:27.752Z,1726848687.752 [WetLabsBB2FL] Loaded 2024-09-20T16:11:27.753Z,1726848687.753 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2024-09-20T16:11:27.754Z,1726848687.754 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC84E0 2024-09-20T16:11:27.754Z,1726848687.754 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 909 2024-09-20T16:11:27.755Z,1726848687.755 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2024-09-20T16:11:27.755Z,1726848687.755 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2024-09-20T16:11:27.782Z,1726848687.782 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2024-09-20T16:11:27.782Z,1726848687.782 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2024-09-20T16:11:28.110Z,1726848688.110 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2024-09-20T16:11:28.111Z,1726848688.111 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2024-09-20T16:11:28.250Z,1726848688.250 [SBIT](DEBUG): Construct Startup Built In Test. 2024-09-20T16:11:28.259Z,1726848688.259 [SBIT] Loaded 2024-09-20T16:11:28.260Z,1726848688.260 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2024-09-20T16:11:28.262Z,1726848688.262 [IBIT](DEBUG): Construct Initiated Built In Test. 2024-09-20T16:11:28.275Z,1726848688.275 [IBIT] Loaded 2024-09-20T16:11:28.275Z,1726848688.275 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2024-09-20T16:11:28.281Z,1726848688.281 [CBIT](DEBUG): Construct Continuous Built In Test. 2024-09-20T16:11:28.384Z,1726848688.384 [CBIT] Loaded 2024-09-20T16:11:28.384Z,1726848688.384 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2024-09-20T16:11:28.384Z,1726848688.384 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2024-09-20T16:11:28.385Z,1726848688.385 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2024-09-20T16:11:28.494Z,1726848688.494 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2024-09-20T16:11:28.500Z,1726848688.500 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2024-09-20T16:11:28.503Z,1726848688.503 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2024-09-20T16:11:28.514Z,1726848688.514 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2024-09-20T16:11:28.515Z,1726848688.515 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C224E0 2024-09-20T16:11:28.515Z,1726848688.515 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 910 2024-09-20T16:11:28.520Z,1726848688.520 [Supervisor](INFO): Main Thread ID is 667 2024-09-20T16:11:28.520Z,1726848688.520 [Supervisor](DEBUG): Running supervisor. 2024-09-20T16:11:28.521Z,1726848688.521 [CommandExec ThreadHandler](INFO): Handler Thread ID is 911 2024-09-20T16:11:28.521Z,1726848688.521 [CommandExec](INFO): Initializing the command executive. 2024-09-20T16:11:28.523Z,1726848688.523 [CommandLine ThreadHandler](INFO): Handler Thread ID is 912 2024-09-20T16:11:28.525Z,1726848688.525 [controlThread ThreadHandler](INFO): Handler Thread ID is 913 2024-09-20T16:11:28.525Z,1726848688.525 [controlThread](DEBUG): Initializing ControlThread 2024-09-20T16:11:28.526Z,1726848688.526 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2024-09-20T16:11:28.528Z,1726848688.528 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2024-09-20T16:11:28.529Z,1726848688.529 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2024-09-20T16:11:28.529Z,1726848688.529 [LoopControl](DEBUG): Initialize LoopControlComponent. 2024-09-20T16:11:28.531Z,1726848688.531 [NavChart](DEBUG): Initialize NavChart Navigation. 2024-09-20T16:11:28.532Z,1726848688.532 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2024-09-20T16:11:28.538Z,1726848688.538 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2024-09-20T16:11:28.538Z,1726848688.538 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2024-09-20T16:11:28.538Z,1726848688.538 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2024-09-20T16:11:28.539Z,1726848688.539 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2024-09-20T16:11:28.539Z,1726848688.539 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2024-09-20T16:11:28.544Z,1726848688.544 [SBIT](INFO): Initialize SBIT Component. 2024-09-20T16:11:28.545Z,1726848688.545 [SBIT](IMPORTANT): git: 2024-09-16_D 2024-09-20T16:11:28.545Z,1726848688.545 [SBIT](INFO): git hash: 0de9f8b4fab077d10194b536abee104cbdf12c92 2024-09-20T16:11:28.545Z,1726848688.545 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2024-09-20T16:11:28.546Z,1726848688.546 [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-09-20T16:11:28.547Z,1726848688.547 [SBIT](INFO): Beginning SBIT in 24.000000 seconds. 2024-09-20T16:11:28.548Z,1726848688.548 [IBIT](INFO): Initialize IBIT Component. 2024-09-20T16:11:28.549Z,1726848688.549 [CBIT](DEBUG): Initialize CBIT Component. 2024-09-20T16:11:28.550Z,1726848688.550 [logger ThreadHandler](INFO): Handler Thread ID is 914 2024-09-20T16:11:28.560Z,1726848688.560 [CBIT](DEBUG): Initialized mux pins. 2024-09-20T16:11:28.560Z,1726848688.560 [CBIT](DEBUG): Initializing the watchdog timer. 2024-09-20T16:11:28.568Z,1726848688.568 [DATMMP ThreadHandler](INFO): Handler Thread ID is 915 2024-09-20T16:11:28.569Z,1726848688.569 [DATMMP](INFO): Start 2024-09-20T16:11:28.569Z,1726848688.569 [DATMMP](INFO): Powering up 2024-09-20T16:11:28.569Z,1726848688.569 [DATMMP](DEBUG): Initializing DATMMP. 2024-09-20T16:11:28.573Z,1726848688.573 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 917 2024-09-20T16:11:28.575Z,1726848688.575 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2024-09-20T16:11:28.584Z,1726848688.584 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2024-09-20T16:11:28.585Z,1726848688.585 [CBIT](DEBUG): Initializing heartbeat. 2024-09-20T16:11:28.588Z,1726848688.588 [Onboard ThreadHandler](INFO): Handler Thread ID is 918 2024-09-20T16:11:28.606Z,1726848688.606 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 919 2024-09-20T16:11:28.625Z,1726848688.625 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 920 2024-09-20T16:11:28.626Z,1726848688.626 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2024-09-20T16:11:28.629Z,1726848688.629 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 922 2024-09-20T16:11:28.632Z,1726848688.632 [WetLabsBB2FL](INFO): Powering up 2024-09-20T16:11:28.634Z,1726848688.634 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 924 2024-09-20T16:11:28.637Z,1726848688.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2024-09-20T16:11:28.637Z,1726848688.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2024-09-20T16:11:28.637Z,1726848688.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2024-09-20T16:11:28.637Z,1726848688.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2024-09-20T16:11:28.637Z,1726848688.637 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2024-09-20T16:11:28.638Z,1726848688.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2024-09-20T16:11:28.638Z,1726848688.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2024-09-20T16:11:28.638Z,1726848688.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2024-09-20T16:11:28.656Z,1726848688.656 [CBIT](DEBUG): Deactivating GF circuits. 2024-09-20T16:11:28.656Z,1726848688.656 [CBIT](DEBUG): Deactivating emergency mode. 2024-09-20T16:11:28.696Z,1726848688.696 [CBIT](DEBUG): Backplane powered. 2024-09-20T16:11:28.697Z,1726848688.697 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2024-09-20T16:11:28.697Z,1726848688.697 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2024-09-20T16:11:28.698Z,1726848688.698 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2024-09-20T16:11:28.699Z,1726848688.699 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2024-09-20T16:11:28.738Z,1726848688.738 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2024-09-20T16:11:28.743Z,1726848688.743 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-20T16:11:28.787Z,1726848688.787 [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-09-20T16:11:28.788Z,1726848688.788 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2024-09-20T16:11:28.788Z,1726848688.788 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2024-09-20T16:11:28.789Z,1726848688.789 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2024-09-20T16:11:28.820Z,1726848688.820 [DATMMP](INFO): Starting 2024-09-20T16:11:29.012Z,1726848689.012 [Radio_Surface](INFO): Powering up 2024-09-20T16:11:29.072Z,1726848689.072 [DATMMP](INFO): Starting 2024-09-20T16:11:29.083Z,1726848689.083 [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-09-20T16:11:29.109Z,1726848689.109 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2024-09-20T16:11:29.110Z,1726848689.110 [Default:A.Wait](DEBUG): Construct Wait. 2024-09-20T16:11:29.112Z,1726848689.112 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2024-09-20T16:11:29.149Z,1726848689.149 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2024-09-20T16:11:29.151Z,1726848689.151 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2024-09-20T16:11:29.194Z,1726848689.194 [Default:E.Execute](DEBUG): Construct Execute. 2024-09-20T16:11:29.203Z,1726848689.203 [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-09-20T16:11:29.209Z,1726848689.209 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,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,Reporter,LogSplitter, 2024-09-20T16:11:29.240Z,1726848689.240 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2024-09-20T16:11:29.305Z,1726848689.305 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m 2024-09-20T16:11:29.307Z,1726848689.307 [PowerOnly](INFO): Powering up loadControl 2024-09-20T16:11:29.324Z,1726848689.324 [DATMMP](INFO): Starting 2024-09-20T16:11:29.325Z,1726848689.325 [Power24vConverter](INFO): Powering up. 2024-09-20T16:11:29.326Z,1726848689.326 [CANONSampler](INFO): Powering down 2024-09-20T16:11:29.372Z,1726848689.372 [DepthRateCalculator](ERROR): Depth measurement is not active 2024-09-20T16:11:29.400Z,1726848689.400 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2024-09-20T16:11:29.408Z,1726848689.408 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2024-09-20T16:11:29.409Z,1726848689.409 [ElevatorServo](DEBUG): Initializing EZServoServo. 2024-09-20T16:11:29.417Z,1726848689.417 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2024-09-20T16:11:29.418Z,1726848689.418 [MassServo](DEBUG): Initializing EZServoServo. 2024-09-20T16:11:29.424Z,1726848689.424 [MassServo](DEBUG): Initializing MassServo. 2024-09-20T16:11:29.425Z,1726848689.425 [RudderServo](DEBUG): Initializing EZServoServo. 2024-09-20T16:11:29.432Z,1726848689.432 [RudderServo](DEBUG): Initializing RudderServo. 2024-09-20T16:11:29.433Z,1726848689.433 [ThrusterHE](DEBUG): Initializing EZServoServo. 2024-09-20T16:11:29.440Z,1726848689.440 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2024-09-20T16:11:29.576Z,1726848689.576 [DATMMP](INFO): Starting 2024-09-20T16:11:29.828Z,1726848689.828 [DATMMP](INFO): Starting 2024-09-20T16:11:30.080Z,1726848690.080 [DATMMP](INFO): Starting 2024-09-20T16:11:30.332Z,1726848690.332 [DATMMP](INFO): Starting 2024-09-20T16:11:30.585Z,1726848690.585 [DATMMP](INFO): Starting 2024-09-20T16:11:30.836Z,1726848690.836 [DATMMP](INFO): Starting 2024-09-20T16:11:31.088Z,1726848691.088 [DATMMP](INFO): Starting 2024-09-20T16:11:31.244Z,1726848691.244 [WetLabsBB2FL](INFO): Powering down 2024-09-20T16:11:31.340Z,1726848691.340 [DATMMP](INFO): Starting 2024-09-20T16:11:31.592Z,1726848691.592 [DATMMP](INFO): Starting 2024-09-20T16:11:31.844Z,1726848691.844 [DATMMP](INFO): Starting 2024-09-20T16:11:32.096Z,1726848692.096 [DATMMP](INFO): Starting 2024-09-20T16:11:32.348Z,1726848692.348 [DATMMP](INFO): Starting 2024-09-20T16:11:32.600Z,1726848692.600 [DATMMP](INFO): Starting 2024-09-20T16:11:32.852Z,1726848692.852 [DATMMP](INFO): Starting 2024-09-20T16:11:33.104Z,1726848693.104 [DATMMP](INFO): Starting 2024-09-20T16:11:33.356Z,1726848693.356 [DATMMP](INFO): Starting 2024-09-20T16:11:33.611Z,1726848693.611 [DATMMP](INFO): Starting 2024-09-20T16:11:33.860Z,1726848693.860 [DATMMP](INFO): Starting 2024-09-20T16:11:34.112Z,1726848694.112 [DATMMP](INFO): Starting 2024-09-20T16:11:34.364Z,1726848694.364 [DATMMP](INFO): Starting 2024-09-20T16:11:34.616Z,1726848694.616 [DATMMP](INFO): Starting 2024-09-20T16:11:34.868Z,1726848694.868 [DATMMP](INFO): Starting 2024-09-20T16:11:35.120Z,1726848695.120 [DATMMP](INFO): Starting 2024-09-20T16:11:35.201Z,1726848695.201 [ThrusterHE](ERROR): Zero Speed Commanded. 2024-09-20T16:11:35.372Z,1726848695.372 [DATMMP](INFO): Starting 2024-09-20T16:11:35.624Z,1726848695.624 [DATMMP](INFO): Starting 2024-09-20T16:11:35.656Z,1726848695.656 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2024-09-20T16:11:35.876Z,1726848695.876 [DATMMP](INFO): Starting 2024-09-20T16:11:36.128Z,1726848696.128 [DATMMP](INFO): Starting 2024-09-20T16:11:36.380Z,1726848696.380 [DATMMP](INFO): Starting 2024-09-20T16:11:36.632Z,1726848696.632 [DATMMP](INFO): Starting 2024-09-20T16:11:36.884Z,1726848696.884 [DATMMP](INFO): Starting 2024-09-20T16:11:37.136Z,1726848697.136 [DATMMP](INFO): Starting 2024-09-20T16:11:37.388Z,1726848697.388 [DATMMP](INFO): Starting 2024-09-20T16:11:37.640Z,1726848697.640 [DATMMP](INFO): Starting 2024-09-20T16:11:37.892Z,1726848697.892 [DATMMP](INFO): Starting 2024-09-20T16:11:38.144Z,1726848698.144 [DATMMP](INFO): Starting 2024-09-20T16:11:38.396Z,1726848698.396 [DATMMP](INFO): Starting 2024-09-20T16:11:38.648Z,1726848698.648 [DATMMP](INFO): Starting 2024-09-20T16:11:38.900Z,1726848698.900 [DATMMP](INFO): Starting 2024-09-20T16:11:39.152Z,1726848699.152 [DATMMP](INFO): Starting 2024-09-20T16:11:39.404Z,1726848699.404 [DATMMP](INFO): Starting 2024-09-20T16:11:39.656Z,1726848699.656 [DATMMP](INFO): Starting 2024-09-20T16:11:39.908Z,1726848699.908 [DATMMP](INFO): Starting 2024-09-20T16:11:40.160Z,1726848700.160 [DATMMP](INFO): Starting 2024-09-20T16:11:40.161Z,1726848700.161 [DATMMP](INFO): DAT read: 2024-09-20T16:11:40.162Z,1726848700.162 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series 2024-09-20T16:11:40.412Z,1726848700.412 [DATMMP](INFO): Starting 2024-09-20T16:11:40.664Z,1726848700.664 [DATMMP](INFO): Starting 2024-09-20T16:11:40.916Z,1726848700.916 [DATMMP](INFO): Starting 2024-09-20T16:11:41.168Z,1726848701.168 [DATMMP](INFO): Starting 2024-09-20T16:11:41.420Z,1726848701.420 [DATMMP](INFO): Starting 2024-09-20T16:11:41.672Z,1726848701.672 [DATMMP](INFO): Starting 2024-09-20T16:11:41.673Z,1726848701.673 [DATMMP](INFO): DAT read: MF Frequency Band 2024-09-20T16:11:41.674Z,1726848701.674 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2024-09-20T16:11:41.674Z,1726848701.674 [DATMMP](INFO): DAT read: Aug 20 2000 16:11:37 2024-09-20T16:11:41.924Z,1726848701.924 [DATMMP](INFO): Starting 2024-09-20T16:11:42.176Z,1726848702.176 [DATMMP](INFO): Starting 2024-09-20T16:11:42.428Z,1726848702.428 [DATMMP](INFO): Starting 2024-09-20T16:11:42.680Z,1726848702.680 [DATMMP](INFO): Starting 2024-09-20T16:11:42.681Z,1726848702.681 [DATMMP](INFO): DAT read: Features enabled [Bearing] 2024-09-20T16:11:42.682Z,1726848702.682 [DATMMP](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2024-09-20T16:11:42.682Z,1726848702.682 [DATMMP](INFO): commRate: 1200 2024-09-20T16:11:42.932Z,1726848702.932 [DATMMP](INFO): Starting 2024-09-20T16:11:43.184Z,1726848703.184 [DATMMP](INFO): Starting 2024-09-20T16:11:43.436Z,1726848703.436 [DATMMP](INFO): Starting 2024-09-20T16:11:43.688Z,1726848703.688 [DATMMP](INFO): Starting 2024-09-20T16:11:43.940Z,1726848703.940 [DATMMP](INFO): Starting 2024-09-20T16:11:44.192Z,1726848704.192 [DATMMP](INFO): Starting 2024-09-20T16:11:44.444Z,1726848704.444 [DATMMP](INFO): Starting 2024-09-20T16:11:44.696Z,1726848704.696 [DATMMP](INFO): Starting 2024-09-20T16:11:44.748Z,1726848704.748 [DATMMP](INFO): entering command mode 2024-09-20T16:11:44.948Z,1726848704.948 [DATMMP](INFO): Starting 2024-09-20T16:11:44.948Z,1726848704.948 [DATMMP](INFO): DAT read: 2024-09-20T16:11:44.949Z,1726848704.949 [DATMMP](INFO): DAT read: user:1> 2024-09-20T16:11:44.949Z,1726848704.949 [DATMMP](DEBUG): read user prompt 1: user:1> 2024-09-20T16:11:44.949Z,1726848704.949 [DATMMP](INFO): entering MMP mode 2024-09-20T16:11:45.200Z,1726848705.200 [DATMMP](INFO): Starting 2024-09-20T16:11:45.201Z,1726848705.201 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:11:45.202Z,1726848705.202 [DATMMP](INFO): status rx: x1 2024-09-20T16:11:45.203Z,1726848705.203 [DATMMP](INFO): MMP status: 1: started 2024-09-20T16:11:45.203Z,1726848705.203 [DATMMP](INFO): Received message type: status 2024-09-20T16:11:45.203Z,1726848705.203 [DATMMP](INFO): Handled 2024-09-20T16:11:45.452Z,1726848705.452 [DATMMP](INFO): Starting 2024-09-20T16:11:45.453Z,1726848705.453 [DATMMP](INFO): Sent Tx Power Config 8 2024-09-20T16:11:45.454Z,1726848705.454 [DATMMP](INFO): Setting local address: 8 2024-09-20T16:11:45.455Z,1726848705.455 [DATMMP](INFO): Setting time to: 16:11:45 And date to:9/20/2024 2024-09-20T16:11:45.704Z,1726848705.704 [DATMMP](INFO): Starting 2024-09-20T16:11:45.705Z,1726848705.705 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:11:45.705Z,1726848705.705 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0 2024-09-20T16:11:45.706Z,1726848705.706 [DATMMP](INFO): Received message type: mdmpower 2024-09-20T16:11:45.706Z,1726848705.706 [DATMMP](INFO): Handled 2024-09-20T16:11:45.956Z,1726848705.956 [DATMMP](INFO): Starting 2024-09-20T16:11:45.957Z,1726848705.957 [DATMMP](INFO): GSXN notify for xid: 10 2024-09-20T16:11:45.957Z,1726848705.957 [DATMMP](INFO): sreg_txpower rx: x8 2024-09-20T16:11:45.958Z,1726848705.958 [DATMMP](INFO): Received message type: sreg_txpower 2024-09-20T16:11:45.958Z,1726848705.958 [DATMMP](INFO): Match for : sreg_txpower 2024-09-20T16:11:45.958Z,1726848705.958 [DATMMP](INFO): ACK 2024-09-20T16:11:46.208Z,1726848706.208 [DATMMP](INFO): Starting 2024-09-20T16:11:46.209Z,1726848706.209 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:11:46.209Z,1726848706.209 [DATMMP](INFO): mdmlocal rx: x8 x0 x0 x0 2024-09-20T16:11:46.210Z,1726848706.210 [DATMMP](INFO): Received message type: mdmlocal 2024-09-20T16:11:46.210Z,1726848706.210 [DATMMP](INFO): Handled 2024-09-20T16:11:46.460Z,1726848706.460 [DATMMP](INFO): Starting 2024-09-20T16:11:46.461Z,1726848706.461 [DATMMP](INFO): GSXN notify for xid: 11 2024-09-20T16:11:46.461Z,1726848706.461 [DATMMP](INFO): sreg_locaddr rx: x8 2024-09-20T16:11:46.461Z,1726848706.461 [DATMMP](INFO): Received message type: sreg_locaddr 2024-09-20T16:11:46.462Z,1726848706.462 [DATMMP](INFO): Match for : sreg_locaddr 2024-09-20T16:11:46.462Z,1726848706.462 [DATMMP](INFO): ACK 2024-09-20T16:11:46.712Z,1726848706.712 [DATMMP](INFO): Starting 2024-09-20T16:11:46.713Z,1726848706.713 [DATMMP](INFO): GSXN notify for xid: 12 2024-09-20T16:11:46.714Z,1726848706.714 [DATMMP](INFO): timedate rx: x0 x10 xB x2D x8 x14 x7C x0 2024-09-20T16:11:46.714Z,1726848706.714 [DATMMP](INFO): Received message type: timedate 2024-09-20T16:11:46.714Z,1726848706.714 [DATMMP](INFO): Match for : timedate 2024-09-20T16:11:46.714Z,1726848706.714 [DATMMP](INFO): ACK 2024-09-20T16:11:46.964Z,1726848706.964 [DATMMP](INFO): Starting 2024-09-20T16:11:46.965Z,1726848706.965 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:11:46.965Z,1726848706.965 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0 2024-09-20T16:11:46.966Z,1726848706.966 [DATMMP](INFO): Received message type: sys_verb 2024-09-20T16:11:46.966Z,1726848706.966 [DATMMP](INFO): Handled 2024-09-20T16:11:47.216Z,1726848707.216 [DATMMP](INFO): Starting 2024-09-20T16:11:47.217Z,1726848707.217 [DATMMP](INFO): GSXN notify for xid: 13 2024-09-20T16:11:47.217Z,1726848707.217 [DATMMP](INFO): sreg_verbosity rx: x0 2024-09-20T16:11:47.217Z,1726848707.217 [DATMMP](INFO): Received message type: sreg_verbosity 2024-09-20T16:11:47.217Z,1726848707.217 [DATMMP](INFO): Match for : sreg_verbosity 2024-09-20T16:11:47.218Z,1726848707.218 [DATMMP](INFO): ACK 2024-09-20T16:11:47.468Z,1726848707.468 [DATMMP](INFO): Starting 2024-09-20T16:11:47.469Z,1726848707.469 [DATMMP](INFO): GSXN notify for xid: 14 2024-09-20T16:11:47.469Z,1726848707.469 [DATMMP](INFO): version rx: x2 x5 2024-09-20T16:11:47.470Z,1726848707.470 [DATMMP](INFO): DatMMPVersion: 2.5 2024-09-20T16:11:47.470Z,1726848707.470 [DATMMP](INFO): Received message type: version 2024-09-20T16:11:47.470Z,1726848707.470 [DATMMP](INFO): Match for : version 2024-09-20T16:11:47.470Z,1726848707.470 [DATMMP](INFO): ACK 2024-09-20T16:11:47.470Z,1726848707.470 [DATMMP](INFO): in MMP mode: config complete going to runnable 2024-09-20T16:11:53.094Z,1726848713.094 [SBIT](IMPORTANT): Beginning Startup BIT 2024-09-20T16:11:53.103Z,1726848713.103 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-20T16:11:57.510Z,1726848717.510 [NAL9602](INFO): Powering up NAL9602 2024-09-20T16:12:01.841Z,1726848721.841 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:12:01.841Z,1726848721.841 [DATMMP](INFO): timesync rx: x0 x1 x1 x0 2024-09-20T16:12:01.842Z,1726848721.842 [DATMMP](INFO): Received message type: timesync 2024-09-20T16:12:01.842Z,1726848721.842 [DATMMP](INFO): Handled 2024-09-20T16:12:04.053Z,1726848724.053 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-20T16:12:04.461Z,1726848724.461 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-20T16:12:08.406Z,1726848728.406 [NAL9602](INFO): NAL9602 initialized 2024-09-20T16:12:11.937Z,1726848731.937 [DATMMP](INFO): GSXN notify for xid: 0 2024-09-20T16:12:11.938Z,1726848731.938 [DATMMP](INFO): timesync rx: x1 x0 x1 x0 2024-09-20T16:12:11.938Z,1726848731.938 [DATMMP](INFO): Received message type: timesync 2024-09-20T16:12:11.938Z,1726848731.938 [DATMMP](INFO): Handled 2024-09-20T16:12:15.389Z,1726848735.389 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan. 2024-09-20T16:12:15.810Z,1726848735.810 [CBIT](IMPORTANT): Beginning ground fault scan 2024-09-20T16:12:26.613Z,1726848746.613 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.002895 CHAN A1 (24V): 0.000280 CHAN A2 (12V): -0.001973 CHAN A3 (5V): -0.001630 CHAN B0 (3.3V): 0.000060 CHAN B1 (3.15aV): -0.000163 CHAN B2 (3.15bV): -0.000263 CHAN B3 (GND): 0.000194 OPEN: -0.000601 Full Scale: +/- 1 mA 2024-09-20T16:12:36.293Z,1726848756.293 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:12:47.223Z,1726848767.223 [SBIT](IMPORTANT): SBIT PASSED 2024-09-20T16:12:47.223Z,1726848767.223 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2024-09-20T16:12:47.224Z,1726848767.224 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool; 2024-09-20T16:12:47.224Z,1726848767.224 [SBIT](IMPORTANT): CANONSampler.simulateHardware=0 bool; 2024-09-20T16:12:47.224Z,1726848767.224 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=260 cubic_centimeter; 2024-09-20T16:12:47.225Z,1726848767.225 [SBIT](IMPORTANT): VerticalControl.massDefault=1 millimeter; 2024-09-20T16:12:47.611Z,1726848767.611 [MissionManager](IMPORTANT): Started mission Startup 2024-09-20T16:12:47.611Z,1726848767.611 [Startup] Running Loop=1 2024-09-20T16:12:47.611Z,1726848767.611 [Startup](DEBUG): Aggregate::initialize Startup 2024-09-20T16:12:47.611Z,1726848767.611 [Startup:A.GoToSurface] Running Loop=1 2024-09-20T16:12:47.611Z,1726848767.611 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-20T16:12:47.612Z,1726848767.612 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-20T16:12:47.612Z,1726848767.612 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-20T16:12:47.613Z,1726848767.613 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-20T16:12:47.613Z,1726848767.613 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-20T16:12:47.613Z,1726848767.613 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-20T16:12:47.614Z,1726848767.614 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-20T16:12:47.615Z,1726848767.615 [Startup:StartupSatComms] Running Loop=1 2024-09-20T16:12:47.616Z,1726848767.616 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2024-09-20T16:12:47.616Z,1726848767.616 [Startup:StartupSatComms:A] Running Loop=1 2024-09-20T16:12:48.083Z,1726848768.083 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2024-09-20T16:12:56.075Z,1726848776.075 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:13:15.465Z,1726848795.465 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:13:35.265Z,1726848815.265 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:13:47.802Z,1726848827.802 [Startup:StartupSatComms:A](INFO): Timed out from 2024-09-20T16:12:47.6Z 2024-09-20T16:13:47.802Z,1726848827.802 [Startup:StartupSatComms:A] Stopped 2024-09-20T16:13:47.803Z,1726848827.803 [Startup:StartupSatComms:B] Running Loop=1 2024-09-20T16:13:48.215Z,1726848828.215 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-20T16:13:52.633Z,1726848832.633 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:14:11.487Z,1726848851.487 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005115 2024-09-20T16:14:12.429Z,1726848852.429 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:14:13.286Z,1726848853.286 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:14:18.652Z,1726848858.652 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240920T160204/Courier0007.lzma 2024-09-20T16:14:19.654Z,1726848859.654 [DataOverHttps](INFO): Moved sent file to Logs/20240920T160204/Courier0007.lzma.bak 2024-09-20T16:14:19.654Z,1726848859.654 [DataOverHttps](INFO): SBD MOMSN=23210849 2024-09-20T16:14:24.253Z,1726848864.253 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:14:28.605Z,1726848868.605 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2024-09-20T16:14:28.605Z,1726848868.605 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2024-09-20T16:14:28.616Z,1726848868.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2024-09-20T16:14:29.059Z,1726848869.059 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2024-09-20T16:14:29.060Z,1726848869.060 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2024-09-20T16:14:34.292Z,1726848874.292 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240920T161116/Courier0000.lzma 2024-09-20T16:14:35.481Z,1726848875.481 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Courier0000.lzma.bak 2024-09-20T16:14:35.481Z,1726848875.481 [DataOverHttps](INFO): SBD MOMSN=23210851 2024-09-20T16:14:35.662Z,1726848875.662 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:14:46.475Z,1726848886.475 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:14:48.013Z,1726848888.013 [Startup:StartupSatComms:B](INFO): Timed out from 2024-09-20T16:13:47.8Z 2024-09-20T16:14:48.013Z,1726848888.013 [Startup:StartupSatComms:B] Stopped 2024-09-20T16:14:48.013Z,1726848888.013 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2024-09-20T16:14:48.013Z,1726848888.013 [Startup:StartupSatComms] Stopped 2024-09-20T16:14:48.013Z,1726848888.013 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2024-09-20T16:14:48.014Z,1726848888.014 [Startup](INFO): Completed Startup 2024-09-20T16:14:48.014Z,1726848888.014 [MissionManager](INFO): Startup is completed. 2024-09-20T16:14:48.015Z,1726848888.015 [MissionManager](INFO): Uninitializing Mission Startup 2024-09-20T16:14:48.015Z,1726848888.015 [Startup] Stopped 2024-09-20T16:14:48.015Z,1726848888.015 [Startup](DEBUG): Aggregate::uninitialize Startup 2024-09-20T16:14:48.015Z,1726848888.015 [Startup:A.GoToSurface] Stopped 2024-09-20T16:14:48.015Z,1726848888.015 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2024-09-20T16:14:48.442Z,1726848888.442 [MissionManager](IMPORTANT): Started mission Default 2024-09-20T16:14:48.443Z,1726848888.443 [Default] Running Loop=1 2024-09-20T16:14:48.443Z,1726848888.443 [Default](DEBUG): Aggregate::initialize Default 2024-09-20T16:14:48.443Z,1726848888.443 [Default:B.GoToSurface] Running Loop=1 2024-09-20T16:14:48.443Z,1726848888.443 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2024-09-20T16:14:48.443Z,1726848888.443 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2024-09-20T16:14:48.443Z,1726848888.443 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2024-09-20T16:14:48.444Z,1726848888.444 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2024-09-20T16:14:48.444Z,1726848888.444 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2024-09-20T16:14:48.444Z,1726848888.444 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2024-09-20T16:14:48.445Z,1726848888.445 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2024-09-20T16:14:48.445Z,1726848888.445 [Default:A.Wait] Running Loop=1 2024-09-20T16:14:48.445Z,1726848888.445 [Default:A.Wait](DEBUG): Initialize Wait Component. 2024-09-20T16:14:50.020Z,1726848890.020 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20240920T160204/Express0008.lzma 2024-09-20T16:14:51.022Z,1726848891.022 [DataOverHttps](INFO): Moved sent file to Logs/20240920T160204/Express0008.lzma.bak 2024-09-20T16:14:51.022Z,1726848891.022 [DataOverHttps](INFO): SBD MOMSN=23210854 2024-09-20T16:14:57.365Z,1726848897.365 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:15:01.786Z,1726848901.786 [Default:A.Wait](INFO): Done Waiting. 2024-09-20T16:15:01.786Z,1726848901.786 [Default:A.Wait] Stopped 2024-09-20T16:15:01.786Z,1726848901.786 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2024-09-20T16:15:02.196Z,1726848902.196 [Default:CheckIn] Running Loop=1 2024-09-20T16:15:02.196Z,1726848902.196 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-20T16:15:02.196Z,1726848902.196 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-20T16:15:02.587Z,1726848902.587 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2024-09-20T16:15:08.279Z,1726848908.279 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:15:19.587Z,1726848919.587 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:15:30.494Z,1726848930.494 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:15:41.403Z,1726848941.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:15:52.311Z,1726848952.311 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:02.819Z,1726848962.819 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:13.317Z,1726848973.317 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:20.576Z,1726848980.576 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:24.224Z,1726848984.224 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:35.133Z,1726848995.133 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:46.041Z,1726849006.041 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:16:55.913Z,1726849015.913 [CommandExec](IMPORTANT): got command strobe off 2024-09-20T16:16:55.914Z,1726849015.914 [CommandExec](IMPORTANT): Deactivating strobe 2024-09-20T16:16:57.003Z,1726849017.003 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:07.857Z,1726849027.857 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:12.242Z,1726849032.242 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-20T16:17:13.455Z,1726849033.455 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161711.00,A,3648.16478,N,12147.27855,W,0.292,359.56,200924,,,A*7C 2024-09-20T16:17:13.458Z,1726849033.458 [NAL9602](INFO): GPS fix at 20240920T161711: (36.802746, -121.787976) 2024-09-20T16:17:13.486Z,1726849033.486 [Default:CheckIn:Read_GPS] Stopped 2024-09-20T16:17:13.486Z,1726849033.486 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-20T16:17:13.914Z,1726849033.914 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2024-09-20T16:17:19.210Z,1726849039.210 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:23.760Z,1726849043.760 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240920T161116/Courier0004.lzma 2024-09-20T16:17:24.762Z,1726849044.762 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Courier0004.lzma.bak 2024-09-20T16:17:24.762Z,1726849044.762 [DataOverHttps](INFO): SBD MOMSN=23210857 2024-09-20T16:17:30.160Z,1726849050.160 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:40.991Z,1726849060.991 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:41.893Z,1726849061.893 [DataOverHttps](INFO): Sending 913 bytes from file Logs/20240920T161116/Express0001.lzma 2024-09-20T16:17:42.894Z,1726849062.894 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Express0001.lzma.bak 2024-09-20T16:17:42.894Z,1726849062.894 [DataOverHttps](INFO): SBD MOMSN=23210860 2024-09-20T16:17:45.373Z,1726849065.373 [NAL9602](INFO): Not Powering down - fast GPS 2024-09-20T16:17:51.499Z,1726849071.499 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:17:58.880Z,1726849078.880 [DataOverHttps](INFO): Sending 386 bytes from file Logs/20240920T161116/Express0005.lzma 2024-09-20T16:17:59.882Z,1726849079.882 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Express0005.lzma.bak 2024-09-20T16:17:59.882Z,1726849079.882 [DataOverHttps](INFO): SBD MOMSN=23210884 2024-09-20T16:18:01.157Z,1726849081.157 [Default:CheckIn:Read_Iridium] Stopped 2024-09-20T16:18:01.157Z,1726849081.157 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-20T16:18:01.157Z,1726849081.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-20T16:18:02.403Z,1726849082.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:18:13.312Z,1726849093.312 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:18:24.624Z,1726849104.624 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:18:35.585Z,1726849115.585 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:18:46.444Z,1726849126.444 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:18:57.348Z,1726849137.348 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:19:08.257Z,1726849148.257 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:19:19.166Z,1726849159.166 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:19:30.071Z,1726849170.071 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:19:40.985Z,1726849180.985 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:19:51.888Z,1726849191.888 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:02.829Z,1726849202.829 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:14.237Z,1726849214.237 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:23.853Z,1726849223.853 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:25.073Z,1726849225.073 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:36.039Z,1726849236.039 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:46.888Z,1726849246.888 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:20:57.796Z,1726849257.796 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:21:08.769Z,1726849268.769 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:21:18.797Z,1726849278.797 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-09-20T16:21:20.015Z,1726849280.015 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:21:26.019Z,1726849286.019 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:21:40.146Z,1726849300.146 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:21:59.539Z,1726849319.539 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:22:18.527Z,1726849338.527 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:22:37.919Z,1726849357.919 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:22:57.311Z,1726849377.311 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:23:01.768Z,1726849381.768 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-20T16:23:01.768Z,1726849381.768 [Default:CheckIn:C.Wait] Stopped 2024-09-20T16:23:01.769Z,1726849381.769 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-20T16:23:01.769Z,1726849381.769 [Default:CheckIn:D] Running Loop=1 2024-09-20T16:23:02.175Z,1726849382.175 [Default:CheckIn:D] Stopped 2024-09-20T16:23:02.175Z,1726849382.175 [Default:CheckIn:E] Running Loop=1 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 8.228876 min 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn:E] Stopped 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn](INFO): Completed Default:CheckIn 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn] Stopped 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn](INFO): Running loop #2 2024-09-20T16:23:02.581Z,1726849382.581 [Default:CheckIn] Running Loop=2 2024-09-20T16:23:02.582Z,1726849382.582 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2024-09-20T16:23:02.582Z,1726849382.582 [Default:CheckIn:Read_GPS] Running Loop=1 2024-09-20T16:23:04.597Z,1726849384.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162302.00,A,3648.16907,N,12147.28178,W,0.175,359.56,200924,,,A*7F 2024-09-20T16:23:04.599Z,1726849384.599 [NAL9602](INFO): GPS fix at 20240920T162302: (36.802818, -121.788030) 2024-09-20T16:23:04.610Z,1726849384.610 [Default:CheckIn:Read_GPS] Stopped 2024-09-20T16:23:04.610Z,1726849384.610 [Default:CheckIn:Read_Iridium] Running Loop=1 2024-09-20T16:23:10.236Z,1726849390.236 [CommandExec](IMPORTANT): got command failComponent 2024-09-20T16:23:10.237Z,1726849390.237 [CommandExec](IMPORTANT): Failed components: 2024-09-20T16:23:10.237Z,1726849390.237 [CommandExec](IMPORTANT): No failed Components. 2024-09-20T16:23:12.423Z,1726849392.423 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240920T161116/Courier0007.lzma 2024-09-20T16:23:13.426Z,1726849393.426 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Courier0007.lzma.bak 2024-09-20T16:23:13.426Z,1726849393.426 [DataOverHttps](INFO): SBD MOMSN=23210898 2024-09-20T16:23:16.703Z,1726849396.703 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:23:18.813Z,1726849398.813 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:23:29.573Z,1726849409.573 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20240920T161116/Express0008.lzma 2024-09-20T16:23:30.574Z,1726849410.574 [DataOverHttps](INFO): Moved sent file to Logs/20240920T161116/Express0008.lzma.bak 2024-09-20T16:23:30.574Z,1726849410.574 [DataOverHttps](INFO): SBD MOMSN=23210901 2024-09-20T16:23:32.085Z,1726849412.085 [Default:CheckIn:Read_Iridium] Stopped 2024-09-20T16:23:32.085Z,1726849412.085 [Default:CheckIn:C.Wait] Running Loop=1 2024-09-20T16:23:32.085Z,1726849412.085 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2024-09-20T16:23:35.290Z,1726849415.290 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check. 2024-09-20T16:23:35.364Z,1726849415.364 [NAL9602](ERROR): received: +CSQ:0 OK 2024-09-20T16:23:36.499Z,1726849416.499 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:23:41.796Z,1726849421.796 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:23:52.726Z,1726849432.726 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:23:55.891Z,1726849435.891 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2024-09-20T16:24:03.635Z,1726849443.635 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:14.548Z,1726849454.548 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:25.452Z,1726849465.452 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:26.646Z,1726849466.646 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:36.023Z,1726849476.023 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:46.865Z,1726849486.865 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:24:57.772Z,1726849497.772 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:25:08.748Z,1726849508.748 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:25:19.589Z,1726849519.589 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:25:30.899Z,1726849530.899 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:25:41.806Z,1726849541.806 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:25:52.306Z,1726849552.306 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:03.225Z,1726849563.225 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:13.725Z,1726849573.725 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:25.037Z,1726849585.037 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:35.944Z,1726849595.944 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:46.451Z,1726849606.451 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:26:57.765Z,1726849617.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:27:09.107Z,1726849629.107 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:27:20.385Z,1726849640.385 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:27:31.697Z,1726849651.697 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:27:42.604Z,1726849662.604 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:27:53.518Z,1726849673.518 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:28:04.823Z,1726849684.823 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:28:07.185Z,1726849687.185 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2024-09-20T16:28:15.731Z,1726849695.731 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:28:26.639Z,1726849706.639 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2024-09-20T16:28:32.652Z,1726849712.652 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2024-09-20T16:28:32.652Z,1726849712.652 [Default:CheckIn:C.Wait] Stopped 2024-09-20T16:28:32.652Z,1726849712.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2024-09-20T16:28:32.653Z,1726849712.653 [Defaul