2021-04-12T04:42:22.384Z,1618202542.384 [Supervisor](DEBUG): Initializing supervisor. 2021-04-12T04:42:22.389Z,1618202542.389 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-04-12T04:42:22.389Z,1618202542.389 [SyncHandler](INFO): Protected caller Thread ID is 835 2021-04-12T04:42:22.390Z,1618202542.390 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-04-12T04:42:22.391Z,1618202542.391 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-04-12T04:42:22.391Z,1618202542.391 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 836 2021-04-12T04:42:22.395Z,1618202542.395 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-04-12T04:42:22.414Z,1618202542.414 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-04-12T04:42:22.415Z,1618202542.415 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-04-12T04:42:22.415Z,1618202542.415 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 837 2021-04-12T04:42:22.417Z,1618202542.417 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-04-12T04:42:22.418Z,1618202542.418 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-04-12T04:42:22.419Z,1618202542.419 [logger ThreadHandler](INFO): Protected caller Thread ID is 838 2021-04-12T04:42:22.423Z,1618202542.423 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-04-12T04:42:22.423Z,1618202542.423 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-04-12T04:42:22.427Z,1618202542.427 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-04-12T04:42:22.525Z,1618202542.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-04-12T04:42:22.527Z,1618202542.527 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-04-12T04:42:23.125Z,1618202543.125 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-04-12T04:42:23.127Z,1618202543.127 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-04-12T04:42:23.341Z,1618202543.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-04-12T04:42:23.342Z,1618202543.342 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-04-12T04:42:23.423Z,1618202543.423 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-04-12T04:42:23.546Z,1618202543.546 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-04-12T04:42:23.547Z,1618202543.547 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-04-12T04:42:23.630Z,1618202543.630 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-04-12T04:42:23.731Z,1618202543.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-04-12T04:42:23.733Z,1618202543.733 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-04-12T04:42:24.083Z,1618202544.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-04-12T04:42:24.085Z,1618202544.085 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-04-12T04:42:24.622Z,1618202544.622 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-04-12T04:42:24.623Z,1618202544.623 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-04-12T04:42:24.771Z,1618202544.771 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-04-12T04:42:24.773Z,1618202544.773 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-04-12T04:42:24.971Z,1618202544.971 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-04-12T04:42:24.972Z,1618202544.972 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-04-12T04:42:25.408Z,1618202545.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-04-12T04:42:25.410Z,1618202545.410 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-04-12T04:42:25.659Z,1618202545.659 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-04-12T04:42:25.660Z,1618202545.660 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-04-12T04:42:25.997Z,1618202545.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-04-12T04:42:26.225Z,1618202546.225 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-04-12T04:42:26.876Z,1618202546.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-04-12T04:42:26.877Z,1618202546.877 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-04-12T04:42:27.217Z,1618202547.217 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-04-12T04:42:27.219Z,1618202547.219 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2021-04-12T04:42:27.222Z,1618202547.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2021-04-12T04:42:27.306Z,1618202547.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2021-04-12T04:42:27.471Z,1618202547.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2021-04-12T04:42:27.583Z,1618202547.583 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2021-04-12T04:42:27.698Z,1618202547.698 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2021-04-12T04:42:27.795Z,1618202547.795 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2021-04-12T04:42:27.989Z,1618202547.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2021-04-12T04:42:28.249Z,1618202548.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-04-12T04:42:28.250Z,1618202548.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2021-04-12T04:42:28.345Z,1618202548.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2021-04-12T04:42:28.445Z,1618202548.445 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2021-04-12T04:42:28.587Z,1618202548.587 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2021-04-12T04:42:28.688Z,1618202548.688 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-12T04:42:28.693Z,1618202548.693 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-04-12T04:42:28.801Z,1618202548.801 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-04-12T04:42:28.802Z,1618202548.802 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-04-12T04:42:28.868Z,1618202548.868 [VerticalControl](DEBUG): Construct VerticalControl. 2021-04-12T04:42:28.929Z,1618202548.929 [VerticalControl] Loaded 2021-04-12T04:42:28.930Z,1618202548.930 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-04-12T04:42:28.932Z,1618202548.932 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-04-12T04:42:28.970Z,1618202548.970 [HorizontalControl] Loaded 2021-04-12T04:42:28.970Z,1618202548.970 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-04-12T04:42:28.972Z,1618202548.972 [SpeedControl](DEBUG): Construct SpeedControl. 2021-04-12T04:42:28.975Z,1618202548.975 [SpeedControl] Loaded 2021-04-12T04:42:28.976Z,1618202548.976 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-04-12T04:42:28.978Z,1618202548.978 [LoopControl](DEBUG): Construct LoopControl. 2021-04-12T04:42:28.978Z,1618202548.978 [LoopControl] Loaded 2021-04-12T04:42:28.979Z,1618202548.979 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-04-12T04:42:28.979Z,1618202548.979 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-04-12T04:42:28.980Z,1618202548.980 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-04-12T04:42:29.036Z,1618202549.036 [DepthRateCalculator] Loaded 2021-04-12T04:42:29.037Z,1618202549.037 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-04-12T04:42:29.041Z,1618202549.041 [PitchRateCalculator] Loaded 2021-04-12T04:42:29.041Z,1618202549.041 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-04-12T04:42:29.051Z,1618202549.051 [SpeedCalculator] Loaded 2021-04-12T04:42:29.052Z,1618202549.052 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-04-12T04:42:29.065Z,1618202549.065 [TempGradientCalculator] Loaded 2021-04-12T04:42:29.065Z,1618202549.065 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-04-12T04:42:29.069Z,1618202549.069 [YawRateCalculator] Loaded 2021-04-12T04:42:29.069Z,1618202549.069 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-04-12T04:42:29.086Z,1618202549.086 [ElevatorOffsetCalculator] Loaded 2021-04-12T04:42:29.086Z,1618202549.086 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-04-12T04:42:29.087Z,1618202549.087 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-04-12T04:42:29.088Z,1618202549.088 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-04-12T04:42:29.130Z,1618202549.130 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-04-12T04:42:29.132Z,1618202549.132 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-04-12T04:42:29.209Z,1618202549.209 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-04-12T04:42:29.210Z,1618202549.210 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-04-12T04:42:29.560Z,1618202549.560 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-04-12T04:42:29.561Z,1618202549.561 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-04-12T04:42:29.689Z,1618202549.689 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-04-12T04:42:29.690Z,1618202549.690 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-04-12T04:42:30.341Z,1618202550.341 [AHRS_M2] Loaded 2021-04-12T04:42:30.341Z,1618202550.341 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-04-12T04:42:30.585Z,1618202550.585 [BackseatComponent] Loaded 2021-04-12T04:42:30.585Z,1618202550.585 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-04-12T04:42:30.587Z,1618202550.587 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408FE4E0 2021-04-12T04:42:30.587Z,1618202550.587 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 920 2021-04-12T04:42:30.590Z,1618202550.590 [LcmUniversalReporter] Loaded 2021-04-12T04:42:30.590Z,1618202550.590 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-04-12T04:42:31.342Z,1618202551.342 [BPC1] Loaded 2021-04-12T04:42:31.343Z,1618202551.343 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-04-12T04:42:31.491Z,1618202551.491 [DataOverHttps] Loaded 2021-04-12T04:42:31.491Z,1618202551.491 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-04-12T04:42:31.492Z,1618202551.492 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4092E4E0 2021-04-12T04:42:31.493Z,1618202551.493 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921 2021-04-12T04:42:31.512Z,1618202551.512 [Depth_Keller] Loaded 2021-04-12T04:42:31.512Z,1618202551.512 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-04-12T04:42:31.516Z,1618202551.516 [DropWeight] Loaded 2021-04-12T04:42:31.517Z,1618202551.517 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-04-12T04:42:31.575Z,1618202551.575 [NAL9602] Loaded 2021-04-12T04:42:31.575Z,1618202551.575 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-04-12T04:42:31.603Z,1618202551.603 [Onboard] Loaded 2021-04-12T04:42:31.603Z,1618202551.603 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-04-12T04:42:31.604Z,1618202551.604 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4095E4E0 2021-04-12T04:42:31.605Z,1618202551.605 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922 2021-04-12T04:42:31.617Z,1618202551.617 [Radio_Surface] Loaded 2021-04-12T04:42:31.618Z,1618202551.618 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-04-12T04:42:31.619Z,1618202551.619 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098E4E0 2021-04-12T04:42:31.619Z,1618202551.619 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923 2021-04-12T04:42:31.660Z,1618202551.660 [RDI_Pathfinder] Loaded 2021-04-12T04:42:31.661Z,1618202551.661 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2021-04-12T04:42:31.740Z,1618202551.740 [DAT] Loaded 2021-04-12T04:42:31.740Z,1618202551.740 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-04-12T04:42:31.740Z,1618202551.740 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-04-12T04:42:31.741Z,1618202551.741 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-04-12T04:42:31.970Z,1618202551.970 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-04-12T04:42:31.970Z,1618202551.970 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-04-12T04:42:31.983Z,1618202551.983 [NavChart] Loaded 2021-04-12T04:42:31.983Z,1618202551.983 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-04-12T04:42:31.989Z,1618202551.989 [UniversalFixResidualReporter] Loaded 2021-04-12T04:42:31.989Z,1618202551.989 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-04-12T04:42:31.990Z,1618202551.990 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-04-12T04:42:32.330Z,1618202552.330 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-04-12T04:42:32.667Z,1618202552.667 [SBIT](DEBUG): Construct Startup Built In Test. 2021-04-12T04:42:32.677Z,1618202552.677 [SBIT] Loaded 2021-04-12T04:42:32.677Z,1618202552.677 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-04-12T04:42:32.680Z,1618202552.680 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-04-12T04:42:32.693Z,1618202552.693 [IBIT] Loaded 2021-04-12T04:42:32.693Z,1618202552.693 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-04-12T04:42:32.698Z,1618202552.698 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-04-12T04:42:32.795Z,1618202552.795 [CBIT] Loaded 2021-04-12T04:42:32.795Z,1618202552.795 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-04-12T04:42:32.796Z,1618202552.796 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-04-12T04:42:32.796Z,1618202552.796 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-04-12T04:42:32.928Z,1618202552.928 [BuoyancyServo] Loaded 2021-04-12T04:42:32.928Z,1618202552.928 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-04-12T04:42:32.947Z,1618202552.947 [ElevatorServo] Loaded 2021-04-12T04:42:32.948Z,1618202552.948 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-04-12T04:42:32.967Z,1618202552.967 [MassServo] Loaded 2021-04-12T04:42:32.967Z,1618202552.967 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-04-12T04:42:32.984Z,1618202552.984 [RudderServo] Loaded 2021-04-12T04:42:32.985Z,1618202552.985 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-04-12T04:42:33.002Z,1618202553.002 [ThrusterServo] Loaded 2021-04-12T04:42:33.002Z,1618202553.002 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-04-12T04:42:33.002Z,1618202553.002 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-04-12T04:42:33.003Z,1618202553.003 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-04-12T04:42:33.019Z,1618202553.019 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-04-12T04:42:33.020Z,1618202553.020 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-04-12T04:42:33.236Z,1618202553.236 [CTD_Seabird] Loaded 2021-04-12T04:42:33.237Z,1618202553.237 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-04-12T04:42:33.238Z,1618202553.238 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ABB4E0 2021-04-12T04:42:33.238Z,1618202553.238 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924 2021-04-12T04:42:33.257Z,1618202553.257 [PAR_Licor] Loaded 2021-04-12T04:42:33.258Z,1618202553.258 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-04-12T04:42:33.289Z,1618202553.289 [WetLabsBB2FL] Loaded 2021-04-12T04:42:33.289Z,1618202553.289 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-04-12T04:42:33.290Z,1618202553.290 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AEB4E0 2021-04-12T04:42:33.290Z,1618202553.290 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925 2021-04-12T04:42:33.310Z,1618202553.310 [WetLabsUBAT] Loaded 2021-04-12T04:42:33.310Z,1618202553.310 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2021-04-12T04:42:33.311Z,1618202553.311 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B1B4E0 2021-04-12T04:42:33.312Z,1618202553.312 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 926 2021-04-12T04:42:33.312Z,1618202553.312 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-04-12T04:42:33.318Z,1618202553.318 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-04-12T04:42:33.321Z,1618202553.321 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-04-12T04:42:33.331Z,1618202553.331 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-04-12T04:42:33.333Z,1618202553.333 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B4B4E0 2021-04-12T04:42:33.333Z,1618202553.333 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 927 2021-04-12T04:42:33.337Z,1618202553.337 [Supervisor](INFO): Main Thread ID is 829 2021-04-12T04:42:33.338Z,1618202553.338 [Supervisor](DEBUG): Running supervisor. 2021-04-12T04:42:33.338Z,1618202553.338 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928 2021-04-12T04:42:33.341Z,1618202553.341 [controlThread ThreadHandler](INFO): Handler Thread ID is 929 2021-04-12T04:42:33.341Z,1618202553.341 [controlThread](DEBUG): Initializing ControlThread 2021-04-12T04:42:33.342Z,1618202553.342 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-04-12T04:42:33.343Z,1618202553.343 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-04-12T04:42:33.344Z,1618202553.344 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-04-12T04:42:33.345Z,1618202553.345 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-04-12T04:42:33.345Z,1618202553.345 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-04-12T04:42:33.345Z,1618202553.345 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-04-12T04:42:33.346Z,1618202553.346 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-04-12T04:42:33.346Z,1618202553.346 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-04-12T04:42:33.347Z,1618202553.347 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-04-12T04:42:33.347Z,1618202553.347 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-04-12T04:42:33.355Z,1618202553.355 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-04-12T04:42:33.355Z,1618202553.355 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-04-12T04:42:33.355Z,1618202553.355 [SBIT](INFO): Initialize SBIT Component. 2021-04-12T04:42:33.356Z,1618202553.356 [SBIT](IMPORTANT): git: 2021-04-08-2-gfc5b6cfe3 2021-04-12T04:42:33.356Z,1618202553.356 [SBIT](INFO): git hash: fc5b6cfe3a0a617c2655bbe510b7fa07d5046659 2021-04-12T04:42:33.356Z,1618202553.356 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-04-12T04:42:33.357Z,1618202553.357 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020 2021-04-12T04:42:33.359Z,1618202553.359 [SBIT](INFO): Beginning SBIT in 49.000000 seconds. 2021-04-12T04:42:33.359Z,1618202553.359 [IBIT](INFO): Initialize IBIT Component. 2021-04-12T04:42:33.360Z,1618202553.360 [CBIT](DEBUG): Initialize CBIT Component. 2021-04-12T04:42:33.361Z,1618202553.361 [logger ThreadHandler](INFO): Handler Thread ID is 930 2021-04-12T04:42:33.372Z,1618202553.372 [CBIT](DEBUG): Initialized mux pins. 2021-04-12T04:42:33.372Z,1618202553.372 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2021-04-12T04:42:33.372Z,1618202553.372 [CBIT](DEBUG): Initializing the watchdog timer. 2021-04-12T04:42:33.380Z,1618202553.380 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 931 2021-04-12T04:42:33.392Z,1618202553.392 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 932 2021-04-12T04:42:33.393Z,1618202553.393 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-04-12T04:42:33.396Z,1618202553.396 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2021-04-12T04:42:33.397Z,1618202553.397 [CBIT](DEBUG): Initializing heartbeat. 2021-04-12T04:42:33.404Z,1618202553.404 [Onboard ThreadHandler](INFO): Handler Thread ID is 933 2021-04-12T04:42:33.422Z,1618202553.422 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 934 2021-04-12T04:42:33.452Z,1618202553.452 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 935 2021-04-12T04:42:33.453Z,1618202553.453 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-12T04:42:33.458Z,1618202553.458 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 937 2021-04-12T04:42:33.461Z,1618202553.461 [WetLabsBB2FL](INFO): Powering up 2021-04-12T04:42:33.462Z,1618202553.462 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 939 2021-04-12T04:42:33.465Z,1618202553.465 [WetLabsUBAT](INFO): Powering up 2021-04-12T04:42:33.466Z,1618202553.466 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 941 2021-04-12T04:42:33.468Z,1618202553.468 [CBIT](DEBUG): Deactivating GF circuits. 2021-04-12T04:42:33.468Z,1618202553.468 [CBIT](DEBUG): Deactivating emergency mode. 2021-04-12T04:42:33.475Z,1618202553.475 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-04-12T04:42:33.476Z,1618202553.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-04-12T04:42:33.476Z,1618202553.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-04-12T04:42:33.476Z,1618202553.476 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-04-12T04:42:33.476Z,1618202553.476 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-04-12T04:42:33.477Z,1618202553.477 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-04-12T04:42:33.478Z,1618202553.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-04-12T04:42:33.478Z,1618202553.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-04-12T04:42:33.478Z,1618202553.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-04-12T04:42:33.478Z,1618202553.478 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-04-12T04:42:33.478Z,1618202553.478 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-04-12T04:42:33.504Z,1618202553.504 [CBIT](DEBUG): Backplane powered. 2021-04-12T04:42:33.509Z,1618202553.509 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-04-12T04:42:33.551Z,1618202553.551 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-12T04:42:33.588Z,1618202553.588 [MissionManager](DEBUG): 2021-04-12T04:42:33.589Z,1618202553.589 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-04-12T04:42:33.675Z,1618202553.675 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-04-12T04:42:33.676Z,1618202553.676 [Default:A.Wait](DEBUG): Construct Wait. 2021-04-12T04:42:33.678Z,1618202553.678 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-12T04:42:33.714Z,1618202553.714 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-04-12T04:42:33.733Z,1618202553.733 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-04-12T04:42:33.739Z,1618202553.739 [Default:E.Execute](DEBUG): Construct Execute. 2021-04-12T04:42:33.758Z,1618202553.758 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2021-04-12T04:42:33.763Z,1618202553.763 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter, 2021-04-12T04:42:33.767Z,1618202553.767 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-12T04:42:33.828Z,1618202553.828 [Radio_Surface](INFO): Powering up 2021-04-12T04:42:33.957Z,1618202553.957 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2021-04-12T04:42:33.967Z,1618202553.967 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-12T04:42:33.967Z,1618202553.967 [DAT](INFO): Powering up 2021-04-12T04:42:33.967Z,1618202553.967 [DAT](DEBUG): Initializing DAT. 2021-04-12T04:42:34.028Z,1618202554.028 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-04-12T04:42:34.040Z,1618202554.040 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2021-04-12T04:42:34.089Z,1618202554.089 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-04-12T04:42:34.100Z,1618202554.100 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-04-12T04:42:34.102Z,1618202554.102 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-04-12T04:42:34.112Z,1618202554.112 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-04-12T04:42:34.114Z,1618202554.114 [MassServo](DEBUG): Initializing EZServoServo. 2021-04-12T04:42:34.125Z,1618202554.125 [MassServo](DEBUG): Initializing MassServo. 2021-04-12T04:42:34.126Z,1618202554.126 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-12T04:42:34.138Z,1618202554.138 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-12T04:42:34.139Z,1618202554.139 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-04-12T04:42:34.144Z,1618202554.144 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-04-12T04:42:34.199Z,1618202554.199 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2021-04-12T04:42:34.199Z,1618202554.199 [DropWeight] Hardware Fault, FailCount= 1 2021-04-12T04:42:34.199Z,1618202554.199 [DropWeight](ERROR): Hardware Fault 2021-04-12T04:42:34.227Z,1618202554.227 [CommandLine](FAULT): Scheduling is paused 2021-04-12T04:42:34.227Z,1618202554.227 [CBIT](INFO): Critical error at 20210412T044234 2021-04-12T04:42:34.228Z,1618202554.228 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-04-12T04:42:34.230Z,1618202554.230 [CBIT](ERROR): Hardware Fault in component: DropWeight 2021-04-12T04:42:34.231Z,1618202554.231 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2021-04-12T04:42:34.593Z,1618202554.593 [CBIT](INFO): Critical error at 20210412T044234 2021-04-12T04:42:36.072Z,1618202556.072 [WetLabsBB2FL](INFO): Powering down 2021-04-12T04:42:40.486Z,1618202560.486 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-04-12T04:42:42.788Z,1618202562.788 [WetLabsUBAT](INFO): Powering down 2021-04-12T04:42:48.316Z,1618202568.316 [DAT](INFO): commRate: 800 2021-04-12T04:42:50.334Z,1618202570.334 [DAT](INFO): entering command mode 2021-04-12T04:42:50.738Z,1618202570.738 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:51.142Z,1618202571.142 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:51.546Z,1618202571.546 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:52.014Z,1618202572.014 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:52.358Z,1618202572.358 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:52.764Z,1618202572.764 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:53.162Z,1618202573.162 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:53.574Z,1618202573.574 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:54.002Z,1618202574.002 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:54.402Z,1618202574.402 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:54.778Z,1618202574.778 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:55.182Z,1618202575.182 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:55.586Z,1618202575.586 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:56.017Z,1618202576.017 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:56.394Z,1618202576.394 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:56.798Z,1618202576.798 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:57.207Z,1618202577.207 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:57.606Z,1618202577.606 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:58.029Z,1618202578.029 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:58.414Z,1618202578.414 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:58.823Z,1618202578.823 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:59.222Z,1618202579.222 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:42:59.626Z,1618202579.626 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:00.086Z,1618202580.086 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:00.442Z,1618202580.442 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:00.838Z,1618202580.838 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:01.242Z,1618202581.242 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:01.646Z,1618202581.646 [NAL9602](INFO): Powering up NAL9602 2021-04-12T04:43:01.647Z,1618202581.647 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:02.081Z,1618202582.081 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:02.454Z,1618202582.454 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:02.858Z,1618202582.858 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:03.262Z,1618202583.262 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:03.666Z,1618202583.666 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:04.107Z,1618202584.107 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:04.473Z,1618202584.473 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:04.878Z,1618202584.878 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:05.282Z,1618202585.282 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:05.690Z,1618202585.690 [DAT](DEBUG): checking for command mode acknowledgment 2021-04-12T04:43:05.691Z,1618202585.691 [DAT](FAULT): failed to enter command mode 2021-04-12T04:43:06.118Z,1618202586.118 [DAT](INFO): entering command mode 2021-04-12T04:43:06.495Z,1618202586.495 [DAT](INFO): setting verbose to 3 2021-04-12T04:43:06.899Z,1618202586.899 [DAT](INFO): set verbose to 3 2021-04-12T04:43:06.913Z,1618202586.913 [DAT](INFO): setting DatVerbose to 27440 2021-04-12T04:43:07.303Z,1618202587.303 [DAT](INFO): set DatVerbose to 27440 2021-04-12T04:43:07.304Z,1618202587.304 [DAT](INFO): setting transmit power to 8 2021-04-12T04:43:07.707Z,1618202587.707 [DAT](INFO): set transmit power to 8 2021-04-12T04:43:07.707Z,1618202587.707 [DAT](INFO): setting local address to 9 2021-04-12T04:43:08.130Z,1618202588.130 [DAT](INFO): set local address to 9 2021-04-12T04:43:12.562Z,1618202592.562 [NAL9602](INFO): NAL9602 initialized 2021-04-12T04:43:23.092Z,1618202603.092 [SBIT](IMPORTANT): Beginning Startup BIT 2021-04-12T04:43:23.096Z,1618202603.096 [CBIT](IMPORTANT): Beginning ground fault scan 2021-04-12T04:43:34.077Z,1618202614.077 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.009115 CHAN A1 (24V): 0.036130 CHAN A2 (12V): -0.007757 CHAN A3 (5V): -0.002204 CHAN B0 (3.3V): 0.000258 CHAN B1 (3.15aV): -0.000028 CHAN B2 (3.15bV): -0.000142 CHAN B3 (GND): 0.002518 OPEN: -0.003479 Full Scale Calc: 4.765 mA, -1.589 mA 2021-04-12T04:44:00.278Z,1618202640.278 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:44:17.211Z,1618202657.211 [SBIT](IMPORTANT): SBIT PASSED 2021-04-12T04:44:17.211Z,1618202657.211 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-12T04:44:17.610Z,1618202657.610 [MissionManager](IMPORTANT): Started mission Startup 2021-04-12T04:44:17.610Z,1618202657.610 [Startup] Running Loop=1 2021-04-12T04:44:17.610Z,1618202657.610 [Startup](DEBUG): Aggregate::initialize Startup 2021-04-12T04:44:17.610Z,1618202657.610 [Startup:A.GoToSurface] Running Loop=1 2021-04-12T04:44:17.611Z,1618202657.611 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-12T04:44:17.611Z,1618202657.611 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-12T04:44:17.612Z,1618202657.612 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-04-12T04:44:17.612Z,1618202657.612 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-04-12T04:44:17.613Z,1618202657.613 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-04-12T04:44:17.613Z,1618202657.613 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-04-12T04:44:17.614Z,1618202657.614 [Startup:StartupSatComms] Running Loop=1 2021-04-12T04:44:17.614Z,1618202657.614 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-04-12T04:44:17.615Z,1618202657.615 [Startup:StartupSatComms:A] Running Loop=1 2021-04-12T04:44:18.094Z,1618202658.094 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-04-12T04:44:29.367Z,1618202669.367 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:45:08.483Z,1618202708.483 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005000 2021-04-12T04:45:17.935Z,1618202717.935 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-12T04:44:17.6Z 2021-04-12T04:45:17.935Z,1618202717.935 [Startup:StartupSatComms:A] Stopped 2021-04-12T04:45:17.935Z,1618202717.935 [Startup:StartupSatComms:B] Running Loop=1 2021-04-12T04:45:18.339Z,1618202718.339 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-04-12T04:45:25.184Z,1618202725.184 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210411T054348/Courier0013.lzma 2021-04-12T04:45:26.186Z,1618202726.186 [DataOverHttps](INFO): Moved sent file to Logs/20210411T054348/Courier0013.lzma.bak 2021-04-12T04:45:26.187Z,1618202726.187 [DataOverHttps](INFO): SBD MOMSN=15558998 2021-04-12T04:45:30.539Z,1618202730.539 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:45:33.698Z,1618202733.698 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-12T04:45:33.698Z,1618202733.698 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-12T04:45:33.708Z,1618202733.708 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-12T04:45:34.109Z,1618202734.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-12T04:45:34.109Z,1618202734.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2021-04-12T04:45:35.710Z,1618202735.710 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2021-04-12T04:45:42.033Z,1618202742.033 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20210412T043615/Courier0000.lzma 2021-04-12T04:45:43.034Z,1618202743.034 [DataOverHttps](INFO): Moved sent file to Logs/20210412T043615/Courier0000.lzma.bak 2021-04-12T04:45:43.034Z,1618202743.034 [DataOverHttps](INFO): SBD MOMSN=15559000 2021-04-12T04:45:59.082Z,1618202759.082 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T043615/Courier0004.lzma 2021-04-12T04:46:00.078Z,1618202760.078 [DataOverHttps](INFO): Moved sent file to Logs/20210412T043615/Courier0004.lzma.bak 2021-04-12T04:46:00.078Z,1618202760.078 [DataOverHttps](INFO): SBD MOMSN=15559006 2021-04-12T04:46:16.019Z,1618202776.019 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20210412T044222/Courier0000.lzma 2021-04-12T04:46:17.018Z,1618202777.018 [DataOverHttps](INFO): Moved sent file to Logs/20210412T044222/Courier0000.lzma.bak 2021-04-12T04:46:17.018Z,1618202777.018 [DataOverHttps](INFO): SBD MOMSN=15559008 2021-04-12T04:46:18.131Z,1618202778.131 [Startup:StartupSatComms:B](INFO): Timed out from 2021-04-12T04:45:17.9Z 2021-04-12T04:46:18.131Z,1618202778.131 [Startup:StartupSatComms:B] Stopped 2021-04-12T04:46:18.131Z,1618202778.131 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-04-12T04:46:18.131Z,1618202778.131 [Startup:StartupSatComms] Stopped 2021-04-12T04:46:18.132Z,1618202778.132 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-04-12T04:46:18.137Z,1618202778.137 [Startup](INFO): Completed Startup 2021-04-12T04:46:18.137Z,1618202778.137 [MissionManager](INFO): Startup is completed. 2021-04-12T04:46:18.137Z,1618202778.137 [MissionManager](INFO): Uninitializing Mission Startup 2021-04-12T04:46:18.137Z,1618202778.137 [Startup] Stopped 2021-04-12T04:46:18.137Z,1618202778.137 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-04-12T04:46:18.137Z,1618202778.137 [Startup:A.GoToSurface] Stopped 2021-04-12T04:46:18.137Z,1618202778.137 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-04-12T04:46:18.542Z,1618202778.542 [MissionManager](IMPORTANT): Started mission Default 2021-04-12T04:46:18.542Z,1618202778.542 [Default] Running Loop=1 2021-04-12T04:46:18.542Z,1618202778.542 [Default](DEBUG): Aggregate::initialize Default 2021-04-12T04:46:18.542Z,1618202778.542 [Default:B.GoToSurface] Running Loop=1 2021-04-12T04:46:18.542Z,1618202778.542 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-12T04:46:18.542Z,1618202778.542 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-12T04:46:18.543Z,1618202778.543 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-04-12T04:46:18.543Z,1618202778.543 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-04-12T04:46:18.543Z,1618202778.543 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-04-12T04:46:18.544Z,1618202778.544 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-04-12T04:46:18.544Z,1618202778.544 [Default:A.Wait] Running Loop=1 2021-04-12T04:46:18.544Z,1618202778.544 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-04-12T04:46:31.891Z,1618202791.891 [Default:A.Wait](INFO): Done Waiting. 2021-04-12T04:46:31.891Z,1618202791.891 [Default:A.Wait] Stopped 2021-04-12T04:46:31.891Z,1618202791.891 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-04-12T04:46:32.298Z,1618202792.298 [Default:CheckIn] Running Loop=1 2021-04-12T04:46:32.298Z,1618202792.298 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-04-12T04:46:32.298Z,1618202792.298 [Default:CheckIn:Read_GPS] Running Loop=1 2021-04-12T04:46:32.671Z,1618202792.671 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-04-12T04:47:00.419Z,1618202820.419 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210411T054348/Express0014.lzma 2021-04-12T04:47:01.424Z,1618202821.424 [DataOverHttps](INFO): Moved sent file to Logs/20210411T054348/Express0014.lzma.bak 2021-04-12T04:47:01.424Z,1618202821.424 [DataOverHttps](INFO): SBD MOMSN=15559013 2021-04-12T04:47:23.214Z,1618202843.214 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-12T04:47:34.937Z,1618202854.937 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-12T04:48:14.521Z,1618202894.521 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:48:15.682Z,1618202895.682 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2021-04-12T04:48:34.698Z,1618202914.698 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2021-04-12T04:48:34.698Z,1618202914.698 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-12T04:48:34.708Z,1618202914.708 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-12T04:48:35.139Z,1618202915.139 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-12T04:48:35.139Z,1618202915.139 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2021-04-12T04:48:40.377Z,1618202920.377 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:50:22.606Z,1618203022.606 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-12T04:50:55.725Z,1618203055.725 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2021-04-12T04:51:05.452Z,1618203065.452 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2021-04-12T04:51:10.621Z,1618203070.621 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2021-04-12T04:51:10.624Z,1618203070.624 [BPC1](INFO): Received data from all battery sticks. 2021-04-12T04:51:32.443Z,1618203092.443 [Default:CheckIn:Read_GPS](INFO): Timed out from 2021-04-12T04:46:32.3Z 2021-04-12T04:51:32.443Z,1618203092.443 [Default:CheckIn:Read_GPS] Stopped 2021-04-12T04:51:32.443Z,1618203092.443 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-04-12T04:51:32.852Z,1618203092.852 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-04-12T04:51:35.686Z,1618203095.686 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2021-04-12T04:51:35.686Z,1618203095.686 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-12T04:51:35.729Z,1618203095.729 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-12T04:51:36.102Z,1618203096.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-12T04:51:36.102Z,1618203096.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2021-04-12T04:51:39.584Z,1618203099.584 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20210412T044222/Courier0004.lzma 2021-04-12T04:51:40.586Z,1618203100.586 [DataOverHttps](INFO): Moved sent file to Logs/20210412T044222/Courier0004.lzma.bak 2021-04-12T04:51:40.586Z,1618203100.586 [DataOverHttps](INFO): SBD MOMSN=15559015 2021-04-12T04:52:02.062Z,1618203122.062 [DataOverHttps](INFO): Sending 1019 bytes from file Logs/20210412T043615/Express0001.lzma 2021-04-12T04:52:03.066Z,1618203123.066 [DataOverHttps](INFO): Moved sent file to Logs/20210412T043615/Express0001.lzma.bak 2021-04-12T04:52:03.067Z,1618203123.067 [DataOverHttps](INFO): SBD MOMSN=15559017 2021-04-12T04:52:19.105Z,1618203139.105 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20210412T043615/Express0005.lzma 2021-04-12T04:52:20.102Z,1618203140.102 [DataOverHttps](INFO): Moved sent file to Logs/20210412T043615/Express0005.lzma.bak 2021-04-12T04:52:20.102Z,1618203140.102 [DataOverHttps](INFO): SBD MOMSN=15559046 2021-04-12T04:52:35.789Z,1618203155.789 [DataOverHttps](INFO): Sending 731 bytes from file Logs/20210412T044222/Express0001.lzma 2021-04-12T04:52:36.790Z,1618203156.790 [DataOverHttps](INFO): Moved sent file to Logs/20210412T044222/Express0001.lzma.bak 2021-04-12T04:52:36.790Z,1618203156.790 [DataOverHttps](INFO): SBD MOMSN=15559048 2021-04-12T04:52:52.868Z,1618203172.868 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20210412T044222/Express0005.lzma 2021-04-12T04:52:53.870Z,1618203173.870 [DataOverHttps](INFO): Moved sent file to Logs/20210412T044222/Express0005.lzma.bak 2021-04-12T04:52:53.870Z,1618203173.870 [DataOverHttps](INFO): SBD MOMSN=15559063 2021-04-12T04:52:55.381Z,1618203175.381 [Default:CheckIn:Read_Iridium] Stopped 2021-04-12T04:52:55.381Z,1618203175.381 [Default:CheckIn:C.Wait] Running Loop=1 2021-04-12T04:52:55.381Z,1618203175.381 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-04-12T04:53:15.528Z,1618203195.528 [NAL9602](FAULT): GPS failed to acquire within timeout. 2021-04-12T04:53:15.528Z,1618203195.528 [NAL9602] Data Fault, FailCount= 1 2021-04-12T04:53:15.528Z,1618203195.528 [NAL9602](ERROR): Data Fault 2021-04-12T04:53:15.546Z,1618203195.546 [CBIT](ERROR): Data Fault in component: NAL9602 2021-04-12T04:53:15.929Z,1618203195.929 [NAL9602](INFO): Powering down 2021-04-12T04:53:16.755Z,1618203196.755 [CBIT](INFO): Clearing failed state for component NAL9602 2021-04-12T04:53:16.755Z,1618203196.755 [NAL9602] No Fault, FailCount= 1 2021-04-12T04:53:46.229Z,1618203226.229 [NAL9602](INFO): Powering up NAL9602 2021-04-12T04:53:57.142Z,1618203237.142 [NAL9602](INFO): NAL9602 initialized 2021-04-12T04:54:36.737Z,1618203276.737 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2021-04-12T04:54:36.738Z,1618203276.738 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-12T04:54:36.765Z,1618203276.765 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2021-04-12T04:54:37.202Z,1618203277.202 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2021-04-12T04:54:37.202Z,1618203277.202 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2021-04-12T04:57:34.524Z,1618203454.524 [CBIT](INFO): Clearing failed state for component DropWeight 2021-04-12T04:57:34.524Z,1618203454.524 [DropWeight] No Fault, FailCount= 1 2021-04-12T04:57:3