2021-11-10T17:31:45.455Z,1636565505.455 [Supervisor](DEBUG): Initializing supervisor. 2021-11-10T17:31:45.460Z,1636565505.460 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-11-10T17:31:45.460Z,1636565505.460 [SyncHandler](INFO): Protected caller Thread ID is 1138 2021-11-10T17:31:45.461Z,1636565505.461 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-11-10T17:31:45.462Z,1636565505.462 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-11-10T17:31:45.462Z,1636565505.462 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1139 2021-11-10T17:31:45.466Z,1636565505.466 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-11-10T17:31:45.485Z,1636565505.485 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2021-11-10T17:31:45.486Z,1636565505.486 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-11-10T17:31:45.486Z,1636565505.486 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1140 2021-11-10T17:31:45.490Z,1636565505.490 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-11-10T17:31:45.491Z,1636565505.491 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-11-10T17:31:45.492Z,1636565505.492 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1141 2021-11-10T17:31:45.494Z,1636565505.494 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-11-10T17:31:45.495Z,1636565505.495 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2021-11-10T17:31:45.495Z,1636565505.495 [logger ThreadHandler](INFO): Protected caller Thread ID is 1142 2021-11-10T17:31:45.499Z,1636565505.499 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-11-10T17:31:45.500Z,1636565505.500 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-11-10T17:31:45.501Z,1636565505.501 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-11-10T17:31:45.597Z,1636565505.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-11-10T17:31:45.598Z,1636565505.598 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-11-10T17:31:46.194Z,1636565506.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-11-10T17:31:46.194Z,1636565506.194 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-11-10T17:31:46.405Z,1636565506.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-11-10T17:31:46.406Z,1636565506.406 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-11-10T17:31:46.484Z,1636565506.484 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-11-10T17:31:46.606Z,1636565506.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-11-10T17:31:46.606Z,1636565506.606 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-11-10T17:31:46.686Z,1636565506.686 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-11-10T17:31:46.793Z,1636565506.793 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-11-10T17:31:46.794Z,1636565506.794 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-11-10T17:31:47.150Z,1636565507.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-11-10T17:31:47.151Z,1636565507.151 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-11-10T17:31:47.684Z,1636565507.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-11-10T17:31:47.685Z,1636565507.685 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-11-10T17:31:47.833Z,1636565507.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-11-10T17:31:47.834Z,1636565507.834 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-11-10T17:31:48.022Z,1636565508.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-11-10T17:31:48.022Z,1636565508.022 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-11-10T17:31:48.479Z,1636565508.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-11-10T17:31:48.480Z,1636565508.480 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-11-10T17:31:48.715Z,1636565508.715 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-11-10T17:31:48.716Z,1636565508.716 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-11-10T17:31:48.917Z,1636565508.917 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-11-10T17:31:48.918Z,1636565508.918 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-11-10T17:31:49.268Z,1636565509.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-11-10T17:31:49.269Z,1636565509.269 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-11-10T17:31:49.605Z,1636565509.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-11-10T17:31:49.607Z,1636565509.607 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2021-11-10T17:31:49.608Z,1636565509.608 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2021-11-10T17:31:49.691Z,1636565509.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2021-11-10T17:31:49.842Z,1636565509.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2021-11-10T17:31:49.958Z,1636565509.958 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2021-11-10T17:31:50.117Z,1636565510.117 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2021-11-10T17:31:50.211Z,1636565510.211 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2021-11-10T17:31:50.638Z,1636565510.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2021-11-10T17:31:51.138Z,1636565511.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-11-10T17:31:51.139Z,1636565511.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2021-11-10T17:31:51.232Z,1636565511.232 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2021-11-10T17:31:51.330Z,1636565511.330 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2021-11-10T17:31:51.467Z,1636565511.467 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2021-11-10T17:31:51.565Z,1636565511.565 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2021-11-10T17:31:51.575Z,1636565511.575 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-11-10T17:31:51.673Z,1636565511.673 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-11-10T17:31:51.673Z,1636565511.673 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-11-10T17:31:51.728Z,1636565511.728 [VerticalControl](DEBUG): Construct VerticalControl. 2021-11-10T17:31:51.791Z,1636565511.791 [VerticalControl] Loaded 2021-11-10T17:31:51.791Z,1636565511.791 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-11-10T17:31:51.794Z,1636565511.794 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-11-10T17:31:51.833Z,1636565511.833 [HorizontalControl] Loaded 2021-11-10T17:31:51.833Z,1636565511.833 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-11-10T17:31:51.835Z,1636565511.835 [SpeedControl](DEBUG): Construct SpeedControl. 2021-11-10T17:31:51.839Z,1636565511.839 [SpeedControl] Loaded 2021-11-10T17:31:51.839Z,1636565511.839 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-11-10T17:31:51.841Z,1636565511.841 [LoopControl](DEBUG): Construct LoopControl. 2021-11-10T17:31:51.842Z,1636565511.842 [LoopControl] Loaded 2021-11-10T17:31:51.842Z,1636565511.842 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-11-10T17:31:51.843Z,1636565511.843 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-11-10T17:31:51.843Z,1636565511.843 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-11-10T17:31:51.886Z,1636565511.886 [DepthRateCalculator] Loaded 2021-11-10T17:31:51.886Z,1636565511.886 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-11-10T17:31:51.891Z,1636565511.891 [PitchRateCalculator] Loaded 2021-11-10T17:31:51.891Z,1636565511.891 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-11-10T17:31:51.902Z,1636565511.902 [SpeedCalculator] Loaded 2021-11-10T17:31:51.902Z,1636565511.902 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-11-10T17:31:51.916Z,1636565511.916 [TempGradientCalculator] Loaded 2021-11-10T17:31:51.916Z,1636565511.916 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-11-10T17:31:51.920Z,1636565511.920 [YawRateCalculator] Loaded 2021-11-10T17:31:51.921Z,1636565511.921 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-11-10T17:31:51.938Z,1636565511.938 [ElevatorOffsetCalculator] Loaded 2021-11-10T17:31:51.939Z,1636565511.939 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-11-10T17:31:51.939Z,1636565511.939 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-11-10T17:31:51.939Z,1636565511.939 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-11-10T17:31:51.968Z,1636565511.968 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-11-10T17:31:51.968Z,1636565511.968 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-11-10T17:31:52.026Z,1636565512.026 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-11-10T17:31:52.027Z,1636565512.027 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-11-10T17:31:52.356Z,1636565512.356 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-11-10T17:31:52.357Z,1636565512.357 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-11-10T17:31:52.462Z,1636565512.462 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-11-10T17:31:52.462Z,1636565512.462 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-11-10T17:31:52.869Z,1636565512.869 [AHRS_M2] Loaded 2021-11-10T17:31:52.869Z,1636565512.869 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-11-10T17:31:52.903Z,1636565512.903 [BackseatComponent] Loaded 2021-11-10T17:31:52.903Z,1636565512.903 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2021-11-10T17:31:52.904Z,1636565512.904 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409444E0 2021-11-10T17:31:52.905Z,1636565512.905 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1224 2021-11-10T17:31:52.907Z,1636565512.907 [LcmUniversalReporter] Loaded 2021-11-10T17:31:52.908Z,1636565512.908 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2021-11-10T17:31:53.686Z,1636565513.686 [BPC1] Loaded 2021-11-10T17:31:53.687Z,1636565513.687 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-11-10T17:31:53.760Z,1636565513.760 [DataOverHttps] Loaded 2021-11-10T17:31:53.760Z,1636565513.760 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-11-10T17:31:53.761Z,1636565513.761 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409744E0 2021-11-10T17:31:53.762Z,1636565513.762 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1225 2021-11-10T17:31:53.782Z,1636565513.782 [Depth_Keller] Loaded 2021-11-10T17:31:53.782Z,1636565513.782 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-11-10T17:31:53.787Z,1636565513.787 [DropWeight] Loaded 2021-11-10T17:31:53.787Z,1636565513.787 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-11-10T17:31:53.846Z,1636565513.846 [NAL9602] Loaded 2021-11-10T17:31:53.846Z,1636565513.846 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-11-10T17:31:53.875Z,1636565513.875 [Onboard] Loaded 2021-11-10T17:31:53.875Z,1636565513.875 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-11-10T17:31:53.876Z,1636565513.876 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409A44E0 2021-11-10T17:31:53.877Z,1636565513.877 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1226 2021-11-10T17:31:53.889Z,1636565513.889 [Power24vConverter] Loaded 2021-11-10T17:31:53.890Z,1636565513.890 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2021-11-10T17:31:53.904Z,1636565513.904 [Radio_Surface] Loaded 2021-11-10T17:31:53.904Z,1636565513.904 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-11-10T17:31:53.906Z,1636565513.906 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409D44E0 2021-11-10T17:31:53.906Z,1636565513.906 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1227 2021-11-10T17:31:53.949Z,1636565513.949 [RDI_Pathfinder] Loaded 2021-11-10T17:31:53.949Z,1636565513.949 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2021-11-10T17:31:54.030Z,1636565514.030 [DAT] Loaded 2021-11-10T17:31:54.030Z,1636565514.030 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-11-10T17:31:54.031Z,1636565514.031 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-11-10T17:31:54.031Z,1636565514.031 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-11-10T17:31:54.110Z,1636565514.110 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-11-10T17:31:54.111Z,1636565514.111 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-11-10T17:31:54.124Z,1636565514.124 [NavChart] Loaded 2021-11-10T17:31:54.124Z,1636565514.124 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-11-10T17:31:54.130Z,1636565514.130 [UniversalFixResidualReporter] Loaded 2021-11-10T17:31:54.131Z,1636565514.131 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-11-10T17:31:54.131Z,1636565514.131 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-11-10T17:31:54.132Z,1636565514.132 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-11-10T17:31:54.278Z,1636565514.278 [SBIT](DEBUG): Construct Startup Built In Test. 2021-11-10T17:31:54.287Z,1636565514.287 [SBIT] Loaded 2021-11-10T17:31:54.287Z,1636565514.287 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-11-10T17:31:54.290Z,1636565514.290 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-11-10T17:31:54.304Z,1636565514.304 [IBIT] Loaded 2021-11-10T17:31:54.304Z,1636565514.304 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-11-10T17:31:54.310Z,1636565514.310 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-11-10T17:31:54.412Z,1636565514.412 [CBIT] Loaded 2021-11-10T17:31:54.412Z,1636565514.412 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-11-10T17:31:54.413Z,1636565514.413 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-11-10T17:31:54.413Z,1636565514.413 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-11-10T17:31:54.543Z,1636565514.543 [BuoyancyServo] Loaded 2021-11-10T17:31:54.543Z,1636565514.543 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-11-10T17:31:54.563Z,1636565514.563 [ElevatorServo] Loaded 2021-11-10T17:31:54.563Z,1636565514.563 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-11-10T17:31:54.582Z,1636565514.582 [MassServo] Loaded 2021-11-10T17:31:54.583Z,1636565514.583 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-11-10T17:31:54.601Z,1636565514.601 [RudderServo] Loaded 2021-11-10T17:31:54.602Z,1636565514.602 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-11-10T17:31:54.621Z,1636565514.621 [ThrusterServo] Loaded 2021-11-10T17:31:54.621Z,1636565514.621 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-11-10T17:31:54.621Z,1636565514.621 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-11-10T17:31:54.622Z,1636565514.622 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-11-10T17:31:54.633Z,1636565514.633 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-11-10T17:31:54.633Z,1636565514.633 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-11-10T17:31:54.807Z,1636565514.807 [CTD_Seabird] Loaded 2021-11-10T17:31:54.808Z,1636565514.808 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-11-10T17:31:54.809Z,1636565514.809 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B0D4E0 2021-11-10T17:31:54.809Z,1636565514.809 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1228 2021-11-10T17:31:54.829Z,1636565514.829 [PAR_Licor] Loaded 2021-11-10T17:31:54.829Z,1636565514.829 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-11-10T17:31:54.862Z,1636565514.862 [WetLabsBB2FL] Loaded 2021-11-10T17:31:54.862Z,1636565514.862 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-11-10T17:31:54.863Z,1636565514.863 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B3D4E0 2021-11-10T17:31:54.863Z,1636565514.863 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1229 2021-11-10T17:31:54.884Z,1636565514.884 [WetLabsUBAT] Loaded 2021-11-10T17:31:54.884Z,1636565514.884 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2021-11-10T17:31:54.885Z,1636565514.885 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B6D4E0 2021-11-10T17:31:54.885Z,1636565514.885 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1230 2021-11-10T17:31:54.886Z,1636565514.886 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-11-10T17:31:54.892Z,1636565514.892 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-11-10T17:31:54.895Z,1636565514.895 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-11-10T17:31:54.907Z,1636565514.907 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-11-10T17:31:54.908Z,1636565514.908 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B9D4E0 2021-11-10T17:31:54.908Z,1636565514.908 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1231 2021-11-10T17:31:54.913Z,1636565514.913 [Supervisor](INFO): Main Thread ID is 831 2021-11-10T17:31:54.913Z,1636565514.913 [Supervisor](DEBUG): Running supervisor. 2021-11-10T17:31:54.914Z,1636565514.914 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1232 2021-11-10T17:31:54.914Z,1636565514.914 [CommandExec](INFO): Initializing the command executive. 2021-11-10T17:31:54.916Z,1636565514.916 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1233 2021-11-10T17:31:54.918Z,1636565514.918 [controlThread ThreadHandler](INFO): Handler Thread ID is 1234 2021-11-10T17:31:54.918Z,1636565514.918 [controlThread](DEBUG): Initializing ControlThread 2021-11-10T17:31:54.919Z,1636565514.919 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-11-10T17:31:54.921Z,1636565514.921 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-11-10T17:31:54.922Z,1636565514.922 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-11-10T17:31:54.922Z,1636565514.922 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-11-10T17:31:54.923Z,1636565514.923 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-11-10T17:31:54.923Z,1636565514.923 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-11-10T17:31:54.923Z,1636565514.923 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-11-10T17:31:54.924Z,1636565514.924 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-11-10T17:31:54.924Z,1636565514.924 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-11-10T17:31:54.925Z,1636565514.925 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-11-10T17:31:54.931Z,1636565514.931 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-11-10T17:31:54.932Z,1636565514.932 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-11-10T17:31:54.932Z,1636565514.932 [SBIT](INFO): Initialize SBIT Component. 2021-11-10T17:31:54.933Z,1636565514.933 [SBIT](IMPORTANT): git: 2021-11-09A 2021-11-10T17:31:54.933Z,1636565514.933 [SBIT](INFO): git hash: 0f0d2425d2b814912a05763138617759beb4d0e7 2021-11-10T17:31:54.933Z,1636565514.933 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-11-10T17:31:54.935Z,1636565514.935 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2021-11-10T17:31:54.936Z,1636565514.936 [SBIT](INFO): Beginning SBIT in 55.000000 seconds. 2021-11-10T17:31:54.936Z,1636565514.936 [IBIT](INFO): Initialize IBIT Component. 2021-11-10T17:31:54.937Z,1636565514.937 [CBIT](DEBUG): Initialize CBIT Component. 2021-11-10T17:31:54.938Z,1636565514.938 [logger ThreadHandler](INFO): Handler Thread ID is 1235 2021-11-10T17:31:54.949Z,1636565514.949 [CBIT](DEBUG): Initialized mux pins. 2021-11-10T17:31:54.949Z,1636565514.949 [CBIT](DEBUG): Initializing the watchdog timer. 2021-11-10T17:31:54.957Z,1636565514.957 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1236 2021-11-10T17:31:54.969Z,1636565514.969 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1237 2021-11-10T17:31:54.970Z,1636565514.970 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-11-10T17:31:54.973Z,1636565514.973 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2021-11-10T17:31:54.973Z,1636565514.973 [CBIT](DEBUG): Initializing heartbeat. 2021-11-10T17:31:54.981Z,1636565514.981 [Onboard ThreadHandler](INFO): Handler Thread ID is 1238 2021-11-10T17:31:54.998Z,1636565514.998 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1239 2021-11-10T17:31:55.018Z,1636565515.018 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1240 2021-11-10T17:31:55.018Z,1636565515.018 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-11-10T17:31:55.022Z,1636565515.022 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1242 2021-11-10T17:31:55.025Z,1636565515.025 [WetLabsBB2FL](INFO): Powering up 2021-11-10T17:31:55.026Z,1636565515.026 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1244 2021-11-10T17:31:55.029Z,1636565515.029 [WetLabsUBAT](INFO): Powering up 2021-11-10T17:31:55.030Z,1636565515.030 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1246 2021-11-10T17:31:55.034Z,1636565515.034 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-11-10T17:31:55.034Z,1636565515.034 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-11-10T17:31:55.034Z,1636565515.034 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-11-10T17:31:55.034Z,1636565515.034 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-11-10T17:31:55.035Z,1636565515.035 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-11-10T17:31:55.036Z,1636565515.036 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-11-10T17:31:55.045Z,1636565515.045 [CBIT](DEBUG): Deactivating GF circuits. 2021-11-10T17:31:55.045Z,1636565515.045 [CBIT](DEBUG): Deactivating emergency mode. 2021-11-10T17:31:55.081Z,1636565515.081 [CBIT](DEBUG): Backplane powered. 2021-11-10T17:31:55.086Z,1636565515.086 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-11-10T17:31:55.099Z,1636565515.099 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-10T17:31:55.152Z,1636565515.152 [MissionManager](DEBUG): 2021-11-10T17:31:55.193Z,1636565515.193 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-11-10T17:31:55.326Z,1636565515.326 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-11-10T17:31:55.327Z,1636565515.327 [Default:A.Wait](DEBUG): Construct Wait. 2021-11-10T17:31:55.369Z,1636565515.369 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-11-10T17:31:55.413Z,1636565515.413 [Radio_Surface](INFO): Powering up 2021-11-10T17:31:55.437Z,1636565515.437 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-11-10T17:31:55.440Z,1636565515.440 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-11-10T17:31:55.470Z,1636565515.470 [Default:E.Execute](DEBUG): Construct Execute. 2021-11-10T17:31:55.514Z,1636565515.514 [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-11-10T17:31:55.521Z,1636565515.521 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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-11-10T17:31:55.536Z,1636565515.536 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-11-10T17:31:55.627Z,1636565515.627 [Power24vConverter](INFO): Powering up. 2021-11-10T17:31:55.634Z,1636565515.634 [DAT](INFO): Powering up 2021-11-10T17:31:55.634Z,1636565515.634 [DAT](DEBUG): Initializing DAT. 2021-11-10T17:31:55.704Z,1636565515.704 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:55.717Z,1636565515.717 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-11-10T17:31:55.718Z,1636565515.718 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:55.725Z,1636565515.725 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-11-10T17:31:55.726Z,1636565515.726 [MassServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:55.733Z,1636565515.733 [MassServo](DEBUG): Initializing MassServo. 2021-11-10T17:31:55.734Z,1636565515.734 [RudderServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:55.741Z,1636565515.741 [RudderServo](DEBUG): Initializing RudderServo. 2021-11-10T17:31:55.742Z,1636565515.742 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:55.749Z,1636565515.749 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-11-10T17:31:55.751Z,1636565515.751 [CommandExec](FAULT): Scheduling is paused 2021-11-10T17:31:55.761Z,1636565515.761 [CBIT](INFO): Critical error at 20211110T173154 2021-11-10T17:31:55.761Z,1636565515.761 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2021-11-10T17:31:56.677Z,1636565516.677 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-11-10T17:31:56.677Z,1636565516.677 [RudderServo](FAULT): Rudder failed to initialize 2021-11-10T17:31:56.677Z,1636565516.677 [RudderServo] Communications Fault, FailCount= 1 2021-11-10T17:31:56.677Z,1636565516.677 [RudderServo](ERROR): Communications Fault 2021-11-10T17:31:56.777Z,1636565516.777 [CBIT](ERROR): Communications Fault in component: RudderServo 2021-11-10T17:31:56.934Z,1636565516.934 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-11-10T17:31:56.934Z,1636565516.934 [RudderServo](INFO): Powering down 2021-11-10T17:31:57.612Z,1636565517.612 [RudderServo](DEBUG): Initializing EZServoServo. 2021-11-10T17:31:57.637Z,1636565517.637 [WetLabsBB2FL](INFO): Powering down 2021-11-10T17:31:57.730Z,1636565517.730 [RudderServo](DEBUG): Initializing RudderServo. 2021-11-10T17:31:57.734Z,1636565517.734 [CBIT](INFO): Clearing failed state for component RudderServo 2021-11-10T17:31:57.734Z,1636565517.734 [RudderServo] No Fault, FailCount= 1 2021-11-10T17:32:02.049Z,1636565522.049 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2021-11-10T17:32:04.273Z,1636565524.273 [WetLabsUBAT](INFO): Powering down 2021-11-10T17:32:17.799Z,1636565537.799 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2021-11-10T17:32:17.799Z,1636565537.799 [RDI_Pathfinder] Communications Fault, FailCount= 1 2021-11-10T17:32:17.799Z,1636565537.799 [RDI_Pathfinder](ERROR): Communications Fault 2021-11-10T17:32:17.888Z,1636565537.888 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2021-11-10T17:32:18.277Z,1636565538.277 [RDI_Pathfinder](INFO): Powering down 2021-11-10T17:32:19.081Z,1636565539.081 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2021-11-10T17:32:19.081Z,1636565539.081 [RDI_Pathfinder] No Fault, FailCount= 1 2021-11-10T17:32:23.858Z,1636565543.858 [NAL9602](INFO): Powering up NAL9602 2021-11-10T17:32:34.767Z,1636565554.767 [NAL9602](INFO): NAL9602 initialized 2021-11-10T17:32:41.635Z,1636565561.635 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2021-11-10T17:32:41.635Z,1636565561.635 [RDI_Pathfinder] Communications Fault, FailCount= 2 2021-11-10T17:32:41.635Z,1636565561.635 [RDI_Pathfinder](ERROR): Communications Fault 2021-11-10T17:32:41.652Z,1636565561.652 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2021-11-10T17:32:42.109Z,1636565562.109 [RDI_Pathfinder](INFO): Powering down 2021-11-10T17:32:42.895Z,1636565562.895 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2021-11-10T17:32:42.895Z,1636565562.895 [RDI_Pathfinder] No Fault, FailCount= 2 2021-11-10T17:32:46.888Z,1636565566.888 [NAL9602](INFO): SBD MO Status=0, MOMSN=17333, MT Status=0, MTMSN=0 2021-11-10T17:32:46.888Z,1636565566.888 [NAL9602](INFO): No messages in MT queue 2021-11-10T17:32:50.565Z,1636565570.565 [SBIT](IMPORTANT): Beginning Startup BIT 2021-11-10T17:32:50.570Z,1636565570.570 [CBIT](IMPORTANT): Beginning ground fault scan 2021-11-10T17:32:55.795Z,1636565575.795 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-11-10T17:32:55.795Z,1636565575.795 [DAT] Communications Fault, FailCount= 1 2021-11-10T17:32:55.795Z,1636565575.795 [DAT](ERROR): Communications Fault 2021-11-10T17:32:55.910Z,1636565575.910 [CBIT](ERROR): Communications Fault in component: DAT 2021-11-10T17:32:56.222Z,1636565576.222 [DAT](INFO): Powering down 2021-11-10T17:32:57.114Z,1636565577.114 [CBIT](INFO): Clearing failed state for component DAT 2021-11-10T17:32:57.114Z,1636565577.114 [DAT] No Fault, FailCount= 1 2021-11-10T17:32:59.415Z,1636565579.415 [DAT](INFO): Powering up 2021-11-10T17:32:59.416Z,1636565579.416 [DAT](DEBUG): Initializing DAT. 2021-11-10T17:33:01.590Z,1636565581.590 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): -0.011195 CHAN A1 (24V): -0.165766 CHAN A2 (12V): -0.007582 CHAN A3 (5V): -0.002163 CHAN B0 (3.3V): 0.000644 CHAN B1 (3.15aV): 0.000008 CHAN B2 (3.15bV): 0.000016 CHAN B3 (GND): 0.002218 OPEN: 0.004803 Full Scale: +/- 1 mA 2021-11-10T17:33:05.475Z,1636565585.475 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2021-11-10T17:33:05.475Z,1636565585.475 [RDI_Pathfinder] Communications Fault, FailCount= 3 2021-11-10T17:33:05.475Z,1636565585.475 [RDI_Pathfinder](ERROR): Communications Fault 2021-11-10T17:33:05.650Z,1636565585.650 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2021-11-10T17:33:05.652Z,1636565585.652 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2021-11-10T17:33:05.953Z,1636565585.953 [RDI_Pathfinder](INFO): Powering down 2021-11-10T17:33:06.072Z,1636565586.072 [CBIT](INFO): Critical error at 20211110T173305 2021-11-10T17:33:10.729Z,1636565590.729 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173309.00,A,3649.08391,N,12209.33994,W,2.099,203.65,101121,,,A*76 2021-11-10T17:33:10.733Z,1636565590.733 [NAL9602](INFO): GPS fix at 20211110T173309: (36.818065, -122.155666) 2021-11-10T17:33:30.275Z,1636565610.275 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.799875 2021-11-10T17:33:30.275Z,1636565610.275 [SBIT](FAULT): Control surface position failure. 2021-11-10T17:33:43.177Z,1636565623.177 [NAL9602](INFO): Not Powering down - fast GPS 2021-11-10T17:33:44.313Z,1636565624.313 [SBIT](CRITICAL): SBIT FAILED 2021-11-10T17:33:44.313Z,1636565624.313 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2021-11-10T17:33:44.314Z,1636565624.314 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2021-11-10T17:33:44.314Z,1636565624.314 [SBIT](IMPORTANT): CBIT.gf24Offset=143 microampere; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 0.300000 liter_per_second; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=24 ampere_hour; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=231.913961 cubic_centimeter; 2021-11-10T17:33:44.315Z,1636565624.315 [SBIT](IMPORTANT): VerticalControl.massDefault=8.903467 millimeter; 2021-11-10T17:33:44.317Z,1636565624.317 [CBIT](INFO): Critical error at 20211110T173344 2021-11-10T17:33:44.679Z,1636565624.679 [MissionManager](IMPORTANT): Started mission Startup 2021-11-10T17:33:44.679Z,1636565624.679 [Startup] Running Loop=1 2021-11-10T17:33:44.679Z,1636565624.679 [Startup](DEBUG): Aggregate::initialize Startup 2021-11-10T17:33:44.679Z,1636565624.679 [Startup:A.GoToSurface] Running Loop=1 2021-11-10T17:33:44.680Z,1636565624.680 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-10T17:33:44.680Z,1636565624.680 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-10T17:33:44.681Z,1636565624.681 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-10T17:33:44.681Z,1636565624.681 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-10T17:33:44.682Z,1636565624.682 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-10T17:33:44.682Z,1636565624.682 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-10T17:33:44.683Z,1636565624.683 [Startup:StartupSatComms] Running Loop=1 2021-11-10T17:33:44.684Z,1636565624.684 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-11-10T17:33:44.684Z,1636565624.684 [Startup:StartupSatComms:A] Running Loop=1 2021-11-10T17:33:45.119Z,1636565625.119 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-11-10T17:33:46.685Z,1636565626.685 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173345.00,A,3649.08523,N,12209.33962,W,0.214,162.68,101121,,,A*74 2021-11-10T17:33:46.687Z,1636565626.687 [NAL9602](INFO): GPS fix at 20211110T173345: (36.818087, -122.155660) 2021-11-10T17:33:46.698Z,1636565626.698 [Startup:StartupSatComms:A] Stopped 2021-11-10T17:33:46.699Z,1636565626.699 [Startup:StartupSatComms:B] Running Loop=1 2021-11-10T17:33:47.186Z,1636565627.186 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-10T17:33:59.729Z,1636565639.729 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-11-10T17:33:59.729Z,1636565639.729 [DAT] Communications Fault, FailCount= 2 2021-11-10T17:33:59.729Z,1636565639.729 [DAT](ERROR): Communications Fault 2021-11-10T17:33:59.758Z,1636565639.758 [CBIT](ERROR): Communications Fault in component: DAT 2021-11-10T17:34:00.126Z,1636565640.126 [DAT](INFO): Powering down 2021-11-10T17:34:00.972Z,1636565640.972 [CBIT](INFO): Clearing failed state for component DAT 2021-11-10T17:34:00.972Z,1636565640.972 [DAT] No Fault, FailCount= 2 2021-11-10T17:34:03.343Z,1636565643.343 [DAT](INFO): Powering up 2021-11-10T17:34:03.343Z,1636565643.343 [DAT](DEBUG): Initializing DAT. 2021-11-10T17:34:12.311Z,1636565652.311 [NAL9602](INFO): SBD MO Status=2, MOMSN=17334, MT Status=2, MTMSN=0 2021-11-10T17:34:12.311Z,1636565652.311 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2021-11-10T17:34:29.245Z,1636565669.245 [NAL9602](INFO): SBD MO Status=1, MOMSN=17334, MT Status=0, MTMSN=0 2021-11-10T17:34:29.307Z,1636565669.307 [NAL9602](INFO): Sent 38 bytes from file Logs/20211110T163654/Courier0036.lzma 2021-11-10T17:34:29.307Z,1636565669.307 [NAL9602](INFO): Packets left to send: 0 2021-11-10T17:34:41.004Z,1636565681.004 [NAL9602](INFO): SBD MO Status=1, MOMSN=17335, MT Status=0, MTMSN=0 2021-11-10T17:34:41.057Z,1636565681.057 [NAL9602](INFO): Sent 38 bytes from file Logs/20211110T163654/Courier0040.lzma 2021-11-10T17:34:41.057Z,1636565681.057 [NAL9602](INFO): Packets left to send: 0 2021-11-10T17:34:46.742Z,1636565686.742 [Startup:StartupSatComms:B](INFO): Timed out from 2021-11-10T17:33:46.7Z 2021-11-10T17:34:46.742Z,1636565686.742 [Startup:StartupSatComms:B] Stopped 2021-11-10T17:34:46.743Z,1636565686.743 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2021-11-10T17:34:46.743Z,1636565686.743 [Startup:StartupSatComms] Stopped 2021-11-10T17:34:46.743Z,1636565686.743 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2021-11-10T17:34:46.744Z,1636565686.744 [Startup](INFO): Completed Startup 2021-11-10T17:34:46.744Z,1636565686.744 [MissionManager](INFO): Startup is completed. 2021-11-10T17:34:46.744Z,1636565686.744 [MissionManager](INFO): Uninitializing Mission Startup 2021-11-10T17:34:46.744Z,1636565686.744 [Startup] Stopped 2021-11-10T17:34:46.744Z,1636565686.744 [Startup](DEBUG): Aggregate::uninitialize Startup 2021-11-10T17:34:46.744Z,1636565686.744 [Startup:A.GoToSurface] Stopped 2021-11-10T17:34:46.744Z,1636565686.744 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2021-11-10T17:34:47.145Z,1636565687.145 [MissionManager](IMPORTANT): Started mission Default 2021-11-10T17:34:47.145Z,1636565687.145 [Default] Running Loop=1 2021-11-10T17:34:47.145Z,1636565687.145 [Default](DEBUG): Aggregate::initialize Default 2021-11-10T17:34:47.146Z,1636565687.146 [Default:B.GoToSurface] Running Loop=1 2021-11-10T17:34:47.146Z,1636565687.146 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-11-10T17:34:47.146Z,1636565687.146 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-11-10T17:34:47.146Z,1636565687.146 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-11-10T17:34:47.147Z,1636565687.147 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-11-10T17:34:47.147Z,1636565687.147 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-11-10T17:34:47.147Z,1636565687.147 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-11-10T17:34:47.148Z,1636565687.148 [Default:A.Wait] Running Loop=1 2021-11-10T17:34:47.148Z,1636565687.148 [Default:A.Wait](DEBUG): Initialize Wait Component. 2021-11-10T17:34:54.396Z,1636565694.396 [NAL9602](INFO): SBD MO Status=1, MOMSN=17336, MT Status=0, MTMSN=0 2021-11-10T17:34:54.457Z,1636565694.457 [NAL9602](INFO): Sent 332 bytes from file Logs/20211110T173145/Courier0000.lzma 2021-11-10T17:34:54.457Z,1636565694.457 [NAL9602](INFO): Packets left to send: 1 2021-11-10T17:35:00.490Z,1636565700.490 [Default:A.Wait](INFO): Done Waiting. 2021-11-10T17:35:00.490Z,1636565700.490 [Default:A.Wait] Stopped 2021-11-10T17:35:00.490Z,1636565700.490 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2021-11-10T17:35:00.872Z,1636565700.872 [Default:CheckIn] Running Loop=1 2021-11-10T17:35:00.873Z,1636565700.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2021-11-10T17:35:00.873Z,1636565700.873 [Default:CheckIn:Read_GPS] Running Loop=1 2021-11-10T17:35:01.277Z,1636565701.277 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2021-11-10T17:35:02.882Z,1636565702.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173501.00,A,3649.07819,N,12209.33713,W,1.166,192.79,101121,,,A*79 2021-11-10T17:35:02.885Z,1636565702.885 [NAL9602](INFO): GPS fix at 20211110T173501: (36.817970, -122.155619) 2021-11-10T17:35:02.896Z,1636565702.896 [Default:CheckIn:Read_GPS] Stopped 2021-11-10T17:35:02.896Z,1636565702.896 [Default:CheckIn:Read_Iridium] Running Loop=1 2021-11-10T17:35:03.319Z,1636565703.319 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-11-10T17:35:03.691Z,1636565703.691 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-11-10T17:35:03.692Z,1636565703.692 [DAT] Communications Fault, FailCount= 3 2021-11-10T17:35:03.692Z,1636565703.692 [DAT](ERROR): Communications Fault 2021-11-10T17:35:03.743Z,1636565703.743 [CBIT](ERROR): Communications Fault in component: DAT 2021-11-10T17:35:04.098Z,1636565704.098 [DAT](INFO): Powering down 2021-11-10T17:35:04.952Z,1636565704.952 [CBIT](INFO): Clearing failed state for component DAT 2021-11-10T17:35:04.952Z,1636565704.952 [DAT] No Fault, FailCount= 3 2021-11-10T17:35:07.339Z,1636565707.339 [DAT](INFO): Powering up 2021-11-10T17:35:07.339Z,1636565707.339 [DAT](DEBUG): Initializing DAT. 2021-11-10T17:35:18.127Z,1636565718.127 [DataOverHttps](IMPORTANT): SBD MTMSN=20211110T173517 2021-11-10T17:35:18.132Z,1636565718.132 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004974 2021-11-10T17:35:21.936Z,1636565721.936 [NAL9602](INFO): SBD MO Status=1, MOMSN=17337, MT Status=0, MTMSN=0 2021-11-10T17:35:21.985Z,1636565721.985 [NAL9602](INFO): Sent 68 bytes from file Logs/20211110T173145/Courier0000.lzma 2021-11-10T17:35:21.985Z,1636565721.985 [NAL9602](INFO): Packets left to send: 0 2021-11-10T17:35:22.025Z,1636565722.025 [Default:CheckIn:Read_Iridium] Stopped 2021-11-10T17:35:22.025Z,1636565722.025 [Default:CheckIn:C.Wait] Running Loop=1 2021-11-10T17:35:22.025Z,1636565722.025 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2021-11-10T17:35:25.547Z,1636565725.547 [DataOverHttps](IMPORTANT): Could not stat file Logs/20211110T173145/Courier0000.lzma 2021-11-10T17:35:25.547Z,1636565725.547 [DataOverHttps](FAULT): Could not open file Logs/20211110T173145/Courier0000.lzma 2021-11-10T17:35:25.555Z,1636565725.555 [DataOverHttps](INFO): Received command: restart sys 2021-11-10T17:35:25.575Z,1636565725.575 [CommandExec](IMPORTANT): got command restart system 2021-11-10T17:35:27.633Z,1636565727.633 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:27.633Z,1636565727.633 [CommandExec](INFO): Uninitializing the command executive. 2021-11-10T17:35:27.633Z,1636565727.633 [CommandExec](INFO): Uninitializing the command scheduler. 2021-11-10T17:35:27.634Z,1636565727.634 [CommandExec ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:27.753Z,1636565727.753 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2021-11-10T17:35:27.753Z,1636565727.753 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2021-11-10T17:35:27.753Z,1636565727.753 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:27.754Z,1636565727.754 [NavChartDb](INFO): Join timeout helper Thread ID is 1270 2021-11-10T17:35:27.985Z,1636565727.985 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:27.985Z,1636565727.985 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.001Z,1636565728.001 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2021-11-10T17:35:28.001Z,1636565728.001 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.002Z,1636565728.002 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1271 2021-11-10T17:35:28.313Z,1636565728.313 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:28.313Z,1636565728.313 [WetLabsUBAT](INFO): Powering down 2021-11-10T17:35:28.314Z,1636565728.314 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.325Z,1636565728.325 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2021-11-10T17:35:28.325Z,1636565728.325 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.325Z,1636565728.325 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1272 2021-11-10T17:35:28.825Z,1636565728.825 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:28.825Z,1636565728.825 [WetLabsBB2FL](INFO): Powering down 2021-11-10T17:35:28.826Z,1636565728.826 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.833Z,1636565728.833 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2021-11-10T17:35:28.833Z,1636565728.833 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.833Z,1636565728.833 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1273 2021-11-10T17:35:28.937Z,1636565728.937 [CTD_Seabird](INFO): Powering down 2021-11-10T17:35:28.949Z,1636565728.949 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:28.949Z,1636565728.949 [CTD_Seabird](INFO): Powering down 2021-11-10T17:35:28.961Z,1636565728.961 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.975Z,1636565728.975 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2021-11-10T17:35:28.976Z,1636565728.976 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:28.976Z,1636565728.976 [Radio_Surface](INFO): Join timeout helper Thread ID is 1274 2021-11-10T17:35:29.205Z,1636565729.205 [Radio_Surface](INFO): Powering down 2021-11-10T17:35:29.206Z,1636565729.206 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:29.206Z,1636565729.206 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:29.207Z,1636565729.207 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2021-11-10T17:35:29.207Z,1636565729.207 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-11-10T17:35:29.208Z,1636565729.208 [Onboard](INFO): Join timeout helper Thread ID is 1275 2021-11-10T17:35:30.153Z,1636565730.153 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2021-11-10T17:35:30.153Z,1636565730.153 [Onboard ThreadHandler](INFO): Thread cancelled. 2021-11-10T1