2020-06-22T17:36:36.874Z,1592847396.874 [Supervisor](DEBUG): Initializing supervisor. 2020-06-22T17:36:36.878Z,1592847396.878 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-06-22T17:36:36.879Z,1592847396.879 [SyncHandler](INFO): Protected caller Thread ID is 4027 2020-06-22T17:36:36.879Z,1592847396.879 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-06-22T17:36:36.880Z,1592847396.880 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-06-22T17:36:36.881Z,1592847396.881 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4028 2020-06-22T17:36:36.884Z,1592847396.884 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-06-22T17:36:36.898Z,1592847396.898 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-06-22T17:36:36.899Z,1592847396.899 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-06-22T17:36:36.899Z,1592847396.899 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4029 2020-06-22T17:36:36.901Z,1592847396.901 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-06-22T17:36:36.902Z,1592847396.902 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-06-22T17:36:36.902Z,1592847396.902 [logger ThreadHandler](INFO): Protected caller Thread ID is 4030 2020-06-22T17:36:36.905Z,1592847396.905 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-06-22T17:36:36.906Z,1592847396.906 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-06-22T17:36:36.907Z,1592847396.907 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-06-22T17:36:37.073Z,1592847397.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-06-22T17:36:37.074Z,1592847397.074 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-06-22T17:36:37.682Z,1592847397.682 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-06-22T17:36:37.683Z,1592847397.683 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-06-22T17:36:37.886Z,1592847397.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-06-22T17:36:37.887Z,1592847397.887 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-06-22T17:36:37.988Z,1592847397.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-06-22T17:36:37.988Z,1592847397.988 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-06-22T17:36:38.093Z,1592847398.093 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-06-22T17:36:38.093Z,1592847398.093 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-06-22T17:36:38.177Z,1592847398.177 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-06-22T17:36:38.319Z,1592847398.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-06-22T17:36:38.319Z,1592847398.319 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-06-22T17:36:38.621Z,1592847398.621 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-06-22T17:36:38.622Z,1592847398.622 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-06-22T17:36:39.092Z,1592847399.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-06-22T17:36:39.092Z,1592847399.092 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-06-22T17:36:39.241Z,1592847399.241 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-06-22T17:36:39.241Z,1592847399.241 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-06-22T17:36:39.443Z,1592847399.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-06-22T17:36:39.444Z,1592847399.444 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-06-22T17:36:39.928Z,1592847399.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-06-22T17:36:39.929Z,1592847399.929 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-06-22T17:36:40.154Z,1592847400.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-06-22T17:36:40.155Z,1592847400.155 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-06-22T17:36:40.389Z,1592847400.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-06-22T17:36:40.390Z,1592847400.390 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-06-22T17:36:41.329Z,1592847401.329 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-06-22T17:36:41.330Z,1592847401.330 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-06-22T17:36:41.669Z,1592847401.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-06-22T17:36:41.671Z,1592847401.671 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-06-22T17:36:41.672Z,1592847401.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-06-22T17:36:41.759Z,1592847401.759 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-06-22T17:36:41.925Z,1592847401.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-06-22T17:36:42.037Z,1592847402.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-06-22T17:36:42.128Z,1592847402.128 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-06-22T17:36:42.229Z,1592847402.229 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-06-22T17:36:42.447Z,1592847402.447 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-06-22T17:36:42.685Z,1592847402.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-06-22T17:36:42.686Z,1592847402.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-06-22T17:36:42.785Z,1592847402.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-06-22T17:36:42.889Z,1592847402.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-06-22T17:36:43.069Z,1592847403.069 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-06-22T17:36:43.236Z,1592847403.236 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-06-22T17:36:43.248Z,1592847403.248 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-06-22T17:36:43.252Z,1592847403.252 [Module Loader](ERROR): Cannot load library: Modules/Dock.so: wrong ELF class: ELFCLASS64 2020-06-22T17:36:43.252Z,1592847403.252 [Module Loader](ERROR): Could not load the module at Modules/Dock.so 2020-06-22T17:36:43.253Z,1592847403.253 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-06-22T17:36:43.320Z,1592847403.320 [VerticalControl](DEBUG): Construct VerticalControl. 2020-06-22T17:36:43.429Z,1592847403.429 [VerticalControl] Loaded 2020-06-22T17:36:43.430Z,1592847403.430 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-06-22T17:36:43.435Z,1592847403.435 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-06-22T17:36:43.501Z,1592847403.501 [HorizontalControl] Loaded 2020-06-22T17:36:43.502Z,1592847403.502 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-06-22T17:36:43.506Z,1592847403.506 [SpeedControl](DEBUG): Construct SpeedControl. 2020-06-22T17:36:43.512Z,1592847403.512 [SpeedControl] Loaded 2020-06-22T17:36:43.512Z,1592847403.512 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-06-22T17:36:43.517Z,1592847403.517 [LoopControl](DEBUG): Construct LoopControl. 2020-06-22T17:36:43.517Z,1592847403.517 [LoopControl] Loaded 2020-06-22T17:36:43.517Z,1592847403.517 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-06-22T17:36:43.518Z,1592847403.518 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-06-22T17:36:43.518Z,1592847403.518 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-06-22T17:36:43.579Z,1592847403.579 [DepthRateCalculator] Loaded 2020-06-22T17:36:43.579Z,1592847403.579 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-06-22T17:36:43.588Z,1592847403.588 [PitchRateCalculator] Loaded 2020-06-22T17:36:43.588Z,1592847403.588 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-06-22T17:36:43.608Z,1592847403.608 [SpeedCalculator] Loaded 2020-06-22T17:36:43.608Z,1592847403.608 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-06-22T17:36:43.632Z,1592847403.632 [TempGradientCalculator] Loaded 2020-06-22T17:36:43.633Z,1592847403.633 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-06-22T17:36:43.641Z,1592847403.641 [YawRateCalculator] Loaded 2020-06-22T17:36:43.641Z,1592847403.641 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-06-22T17:36:43.674Z,1592847403.674 [ElevatorOffsetCalculator] Loaded 2020-06-22T17:36:43.675Z,1592847403.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-06-22T17:36:43.675Z,1592847403.675 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-06-22T17:36:43.676Z,1592847403.676 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-06-22T17:36:43.723Z,1592847403.723 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-06-22T17:36:43.724Z,1592847403.724 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-06-22T17:36:43.834Z,1592847403.834 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-06-22T17:36:43.835Z,1592847403.835 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-06-22T17:36:44.160Z,1592847404.160 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-06-22T17:36:44.161Z,1592847404.161 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-06-22T17:36:44.279Z,1592847404.279 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-06-22T17:36:44.280Z,1592847404.280 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-06-22T17:36:44.913Z,1592847404.913 [AHRS_M2] Loaded 2020-06-22T17:36:44.913Z,1592847404.913 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-06-22T17:36:45.015Z,1592847405.015 [DataOverHttps] Loaded 2020-06-22T17:36:45.015Z,1592847405.015 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-06-22T17:36:45.016Z,1592847405.016 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0 2020-06-22T17:36:45.017Z,1592847405.017 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4113 2020-06-22T17:36:45.034Z,1592847405.034 [Depth_Keller] Loaded 2020-06-22T17:36:45.034Z,1592847405.034 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-06-22T17:36:45.133Z,1592847405.133 [NAL9602] Loaded 2020-06-22T17:36:45.134Z,1592847405.134 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-06-22T17:36:45.184Z,1592847405.184 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-06-22T17:36:45.184Z,1592847405.184 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-06-22T17:36:45.185Z,1592847405.185 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-06-22T17:36:45.185Z,1592847405.185 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-06-22T17:36:45.186Z,1592847405.186 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-06-22T17:36:45.186Z,1592847405.186 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-06-22T17:36:45.187Z,1592847405.187 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-06-22T17:36:45.187Z,1592847405.187 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-06-22T17:36:45.187Z,1592847405.187 [Onboard] Loaded 2020-06-22T17:36:45.188Z,1592847405.188 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-06-22T17:36:45.189Z,1592847405.189 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0 2020-06-22T17:36:45.189Z,1592847405.189 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4114 2020-06-22T17:36:45.197Z,1592847405.197 [Radio_Surface] Loaded 2020-06-22T17:36:45.197Z,1592847405.197 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-06-22T17:36:45.198Z,1592847405.198 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091B4E0 2020-06-22T17:36:45.198Z,1592847405.198 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4115 2020-06-22T17:36:45.245Z,1592847405.245 [RDI_Pathfinder] Loaded 2020-06-22T17:36:45.246Z,1592847405.246 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-06-22T17:36:45.375Z,1592847405.375 [DAT] Loaded 2020-06-22T17:36:45.375Z,1592847405.375 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-06-22T17:36:47.356Z,1592847407.356 [BPC1] Loaded 2020-06-22T17:36:47.356Z,1592847407.356 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-06-22T17:36:47.356Z,1592847407.356 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-06-22T17:36:47.357Z,1592847407.357 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-06-22T17:36:47.477Z,1592847407.477 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-06-22T17:36:47.478Z,1592847407.478 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-06-22T17:36:47.502Z,1592847407.502 [NavChart] Loaded 2020-06-22T17:36:47.502Z,1592847407.502 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-06-22T17:36:47.510Z,1592847407.510 [UniversalFixResidualReporter] Loaded 2020-06-22T17:36:47.510Z,1592847407.510 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-06-22T17:36:47.510Z,1592847407.510 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-06-22T17:36:47.511Z,1592847407.511 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-06-22T17:36:47.670Z,1592847407.670 [SBIT](DEBUG): Construct Startup Built In Test. 2020-06-22T17:36:47.681Z,1592847407.681 [SBIT] Loaded 2020-06-22T17:36:47.681Z,1592847407.681 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-06-22T17:36:47.686Z,1592847407.686 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-06-22T17:36:47.699Z,1592847407.699 [IBIT] Loaded 2020-06-22T17:36:47.699Z,1592847407.699 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-06-22T17:36:47.707Z,1592847407.707 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-06-22T17:36:47.844Z,1592847407.844 [CBIT] Loaded 2020-06-22T17:36:47.844Z,1592847407.844 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-06-22T17:36:47.844Z,1592847407.844 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-06-22T17:36:47.845Z,1592847407.845 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-06-22T17:36:47.972Z,1592847407.972 [BuoyancyServo] Loaded 2020-06-22T17:36:47.972Z,1592847407.972 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-06-22T17:36:47.988Z,1592847407.988 [ElevatorServo] Loaded 2020-06-22T17:36:47.988Z,1592847407.988 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-06-22T17:36:48.003Z,1592847408.003 [MassServo] Loaded 2020-06-22T17:36:48.003Z,1592847408.003 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-06-22T17:36:48.018Z,1592847408.018 [RudderServo] Loaded 2020-06-22T17:36:48.018Z,1592847408.018 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-06-22T17:36:48.033Z,1592847408.033 [ThrusterServo] Loaded 2020-06-22T17:36:48.033Z,1592847408.033 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-06-22T17:36:48.033Z,1592847408.033 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-06-22T17:36:48.034Z,1592847408.034 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-06-22T17:36:48.057Z,1592847408.057 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-06-22T17:36:48.058Z,1592847408.058 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-06-22T17:36:48.317Z,1592847408.317 [BackSeatDriver] Loaded 2020-06-22T17:36:48.318Z,1592847408.318 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-06-22T17:36:48.319Z,1592847408.319 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A714E0 2020-06-22T17:36:48.319Z,1592847408.319 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 4116 2020-06-22T17:36:48.403Z,1592847408.403 [CTD_Seabird] Loaded 2020-06-22T17:36:48.403Z,1592847408.403 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-06-22T17:36:48.407Z,1592847408.407 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AA14E0 2020-06-22T17:36:48.408Z,1592847408.408 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4117 2020-06-22T17:36:48.459Z,1592847408.459 [WetLabsBB2FL] Loaded 2020-06-22T17:36:48.460Z,1592847408.460 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-06-22T17:36:48.461Z,1592847408.461 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AD14E0 2020-06-22T17:36:48.461Z,1592847408.461 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4118 2020-06-22T17:36:48.462Z,1592847408.462 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-06-22T17:36:48.468Z,1592847408.468 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-06-22T17:36:48.471Z,1592847408.471 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-06-22T17:36:48.479Z,1592847408.479 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-06-22T17:36:48.480Z,1592847408.480 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B014E0 2020-06-22T17:36:48.480Z,1592847408.480 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4119 2020-06-22T17:36:48.485Z,1592847408.485 [Supervisor](INFO): Main Thread ID is 4026 2020-06-22T17:36:48.485Z,1592847408.485 [Supervisor](DEBUG): Running supervisor. 2020-06-22T17:36:48.486Z,1592847408.486 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4120 2020-06-22T17:36:48.490Z,1592847408.490 [controlThread ThreadHandler](INFO): Handler Thread ID is 4121 2020-06-22T17:36:48.491Z,1592847408.491 [controlThread](DEBUG): Initializing ControlThread 2020-06-22T17:36:48.491Z,1592847408.491 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-06-22T17:36:48.493Z,1592847408.493 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-06-22T17:36:48.494Z,1592847408.494 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-06-22T17:36:48.494Z,1592847408.494 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-06-22T17:36:48.495Z,1592847408.495 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-06-22T17:36:48.495Z,1592847408.495 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-06-22T17:36:48.495Z,1592847408.495 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-06-22T17:36:48.495Z,1592847408.495 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-06-22T17:36:48.496Z,1592847408.496 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-06-22T17:36:48.496Z,1592847408.496 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-06-22T17:36:48.499Z,1592847408.499 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-06-22T17:36:48.500Z,1592847408.500 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-06-22T17:36:48.500Z,1592847408.500 [SBIT](INFO): Initialize SBIT Component. 2020-06-22T17:36:48.500Z,1592847408.500 [SBIT](IMPORTANT): git: 2020_06_10_lcm_load_test-11-gabfb63c 2020-06-22T17:36:48.500Z,1592847408.500 [SBIT](INFO): git hash: abfb63c601513b5a350a433c7b237ca7f0ba1320 2020-06-22T17:36:48.501Z,1592847408.501 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-06-22T17:36:48.501Z,1592847408.501 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-06-22T17:36:48.502Z,1592847408.502 [SBIT](INFO): Beginning SBIT in 76.000000 seconds. 2020-06-22T17:36:48.503Z,1592847408.503 [IBIT](INFO): Initialize IBIT Component. 2020-06-22T17:36:48.504Z,1592847408.504 [CBIT](DEBUG): Initialize CBIT Component. 2020-06-22T17:36:48.504Z,1592847408.504 [logger ThreadHandler](INFO): Handler Thread ID is 4122 2020-06-22T17:36:48.518Z,1592847408.518 [CBIT](DEBUG): Initialized mux pins. 2020-06-22T17:36:48.518Z,1592847408.518 [CBIT](DEBUG): Initializing the watchdog timer. 2020-06-22T17:36:48.522Z,1592847408.522 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4123 2020-06-22T17:36:48.523Z,1592847408.523 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-06-22T17:36:48.531Z,1592847408.531 [Onboard ThreadHandler](INFO): Handler Thread ID is 4124 2020-06-22T17:36:48.543Z,1592847408.543 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-06-22T17:36:48.543Z,1592847408.543 [CBIT](DEBUG): Initializing heartbeat. 2020-06-22T17:36:48.550Z,1592847408.550 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4125 2020-06-22T17:36:48.566Z,1592847408.566 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 4126 2020-06-22T17:36:48.578Z,1592847408.578 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4127 2020-06-22T17:36:48.579Z,1592847408.579 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-06-22T17:36:48.582Z,1592847408.582 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-06-22T17:36:48.584Z,1592847408.584 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4129 2020-06-22T17:36:48.586Z,1592847408.586 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2 2020-06-22T17:36:48.587Z,1592847408.587 [WetLabsBB2FL](INFO): Powering up 2020-06-22T17:36:48.588Z,1592847408.588 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4131 2020-06-22T17:36:48.593Z,1592847408.593 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-06-22T17:36:48.593Z,1592847408.593 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-06-22T17:36:48.593Z,1592847408.593 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-06-22T17:36:48.594Z,1592847408.594 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-06-22T17:36:48.594Z,1592847408.594 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-06-22T17:36:48.594Z,1592847408.594 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-06-22T17:36:48.595Z,1592847408.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-06-22T17:36:48.595Z,1592847408.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-06-22T17:36:48.595Z,1592847408.595 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-06-22T17:36:48.595Z,1592847408.595 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-06-22T17:36:48.596Z,1592847408.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-06-22T17:36:48.596Z,1592847408.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-06-22T17:36:48.596Z,1592847408.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-06-22T17:36:48.596Z,1592847408.596 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-06-22T17:36:48.596Z,1592847408.596 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-06-22T17:36:48.597Z,1592847408.597 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-06-22T17:36:48.614Z,1592847408.614 [CBIT](DEBUG): Deactivating GF circuits. 2020-06-22T17:36:48.614Z,1592847408.614 [CBIT](DEBUG): Deactivating emergency mode. 2020-06-22T17:36:48.650Z,1592847408.650 [CBIT](DEBUG): Backplane powered. 2020-06-22T17:36:48.652Z,1592847408.652 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-06-22T17:36:48.682Z,1592847408.682 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-22T17:36:48.713Z,1592847408.713 [MissionManager](DEBUG): 2020-06-22T17:36:48.730Z,1592847408.730 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-06-22T17:36:48.790Z,1592847408.790 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-06-22T17:36:48.792Z,1592847408.792 [Default:A.Wait](DEBUG): Construct Wait. 2020-06-22T17:36:48.810Z,1592847408.810 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-06-22T17:36:48.849Z,1592847408.849 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-06-22T17:36:48.852Z,1592847408.852 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-06-22T17:36:48.873Z,1592847408.873 [Default:E.Execute](DEBUG): Construct Execute. 2020-06-22T17:36:48.905Z,1592847408.905 [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 2020-06-22T17:36:48.909Z,1592847408.909 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,RDI_Pathfinder,DAT,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-06-22T17:36:48.930Z,1592847408.930 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-06-22T17:36:48.954Z,1592847408.954 [Radio_Surface](INFO): Powering up 2020-06-22T17:36:49.071Z,1592847409.071 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-06-22T17:36:49.114Z,1592847409.114 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-22T17:36:49.114Z,1592847409.114 [DAT](INFO): Powering up 2020-06-22T17:36:49.114Z,1592847409.114 [DAT](DEBUG): Initializing DAT. 2020-06-22T17:36:49.194Z,1592847409.194 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-22T17:36:49.235Z,1592847409.235 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-06-22T17:36:49.514Z,1592847409.514 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:49.522Z,1592847409.522 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:36:49.550Z,1592847409.550 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:49.562Z,1592847409.562 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-06-22T17:36:49.584Z,1592847409.584 [MassServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:49.591Z,1592847409.591 [MassServo](DEBUG): Initializing MassServo. 2020-06-22T17:36:49.604Z,1592847409.604 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:49.610Z,1592847409.610 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-22T17:36:49.628Z,1592847409.628 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:49.634Z,1592847409.634 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-06-22T17:36:49.821Z,1592847409.821 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-06-22T17:36:50.462Z,1592847410.462 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-06-22T17:36:50.462Z,1592847410.462 [RudderServo](FAULT): Rudder failed to initialize 2020-06-22T17:36:50.462Z,1592847410.462 [RudderServo] Communications Fault, FailCount= 1 2020-06-22T17:36:50.462Z,1592847410.462 [RudderServo](ERROR): Communications Fault 2020-06-22T17:36:50.614Z,1592847410.614 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-06-22T17:36:50.791Z,1592847410.791 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-22T17:36:50.791Z,1592847410.791 [RudderServo](INFO): Powering down 2020-06-22T17:36:51.198Z,1592847411.198 [WetLabsBB2FL](INFO): Powering down 2020-06-22T17:36:51.444Z,1592847411.444 [RudderServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:36:51.563Z,1592847411.563 [RudderServo](DEBUG): Initializing RudderServo. 2020-06-22T17:36:51.567Z,1592847411.567 [CBIT](INFO): Clearing failed state for component RudderServo 2020-06-22T17:36:51.567Z,1592847411.567 [RudderServo] No Fault, FailCount= 1 2020-06-22T17:36:55.612Z,1592847415.612 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-06-22T17:36:58.775Z,1592847418.775 [CBIT](CRITICAL): Environmental Failure. Press:14.415450 PSI. Humidity:54%. Temp:21 C. ABORTING MISSION 2020-06-22T17:36:58.776Z,1592847418.776 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2020-06-22T17:36:59.225Z,1592847419.225 [CommandLine](FAULT): Scheduling is paused 2020-06-22T17:36:59.225Z,1592847419.225 [CBIT](INFO): Critical error at 20200622T173658 2020-06-22T17:37:02.620Z,1592847422.620 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004632 2020-06-22T17:37:10.538Z,1592847430.538 [BuoyancyServo](FAULT): Overload Error 2020-06-22T17:37:10.538Z,1592847430.538 [BuoyancyServo] Hardware Fault, FailCount= 1 2020-06-22T17:37:10.538Z,1592847430.538 [BuoyancyServo](ERROR): Hardware Fault 2020-06-22T17:37:10.579Z,1592847430.579 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2020-06-22T17:37:11.228Z,1592847431.228 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-06-22T17:37:11.228Z,1592847431.228 [RDI_Pathfinder] Communications Fault, FailCount= 1 2020-06-22T17:37:11.228Z,1592847431.228 [RDI_Pathfinder](ERROR): Communications Fault 2020-06-22T17:37:11.271Z,1592847431.271 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-06-22T17:37:11.708Z,1592847431.708 [RDI_Pathfinder](INFO): Powering down 2020-06-22T17:37:12.483Z,1592847432.483 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:37:12.483Z,1592847432.483 [BuoyancyServo](INFO): Powering down 2020-06-22T17:37:12.655Z,1592847432.655 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-06-22T17:37:12.655Z,1592847432.655 [RDI_Pathfinder] No Fault, FailCount= 1 2020-06-22T17:37:13.299Z,1592847433.299 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2020-06-22T17:37:13.299Z,1592847433.299 [BuoyancyServo] No Fault, FailCount= 1 2020-06-22T17:37:13.664Z,1592847433.664 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:37:13.670Z,1592847433.670 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:37:15.275Z,1592847435.275 [NAL9602](INFO): Powering up NAL9602 2020-06-22T17:37:22.576Z,1592847442.576 [CommandLine](INFO): End of History 2020-06-22T17:37:22.623Z,1592847442.623 [CommandLine](INFO): End of History 2020-06-22T17:37:22.661Z,1592847442.661 [CommandLine](INFO): End of History 2020-06-22T17:37:22.664Z,1592847442.664 [CommandLine](INFO): End of History 2020-06-22T17:37:22.697Z,1592847442.697 [CommandLine](INFO): End of History 2020-06-22T17:37:22.700Z,1592847442.700 [CommandLine](INFO): End of History 2020-06-22T17:37:22.702Z,1592847442.702 [CommandLine](INFO): End of History 2020-06-22T17:37:22.704Z,1592847442.704 [CommandLine](INFO): End of History 2020-06-22T17:37:22.831Z,1592847442.831 [CommandLine](INFO): End of History 2020-06-22T17:37:22.833Z,1592847442.833 [CommandLine](INFO): End of History 2020-06-22T17:37:22.835Z,1592847442.835 [CommandLine](INFO): End of History 2020-06-22T17:37:22.837Z,1592847442.837 [CommandLine](INFO): End of History 2020-06-22T17:37:22.839Z,1592847442.839 [CommandLine](INFO): End of History 2020-06-22T17:37:22.841Z,1592847442.841 [CommandLine](INFO): End of History 2020-06-22T17:37:22.843Z,1592847442.843 [CommandLine](INFO): End of History 2020-06-22T17:37:22.855Z,1592847442.855 [CommandLine](INFO): End of History 2020-06-22T17:37:22.857Z,1592847442.857 [CommandLine](INFO): End of History 2020-06-22T17:37:22.867Z,1592847442.867 [CommandLine](INFO): End of History 2020-06-22T17:37:22.869Z,1592847442.869 [CommandLine](INFO): End of History 2020-06-22T17:37:22.895Z,1592847442.895 [CommandLine](INFO): End of History 2020-06-22T17:37:22.897Z,1592847442.897 [CommandLine](INFO): End of History 2020-06-22T17:37:22.924Z,1592847442.924 [CommandLine](INFO): End of History 2020-06-22T17:37:22.925Z,1592847442.925 [CommandLine](INFO): End of History 2020-06-22T17:37:22.927Z,1592847442.927 [CommandLine](INFO): End of History 2020-06-22T17:37:23.027Z,1592847443.027 [CommandLine](INFO): End of History 2020-06-22T17:37:23.029Z,1592847443.029 [CommandLine](INFO): End of History 2020-06-22T17:37:23.031Z,1592847443.031 [CommandLine](INFO): End of History 2020-06-22T17:37:26.179Z,1592847446.179 [NAL9602](INFO): NAL9602 initialized 2020-06-22T17:37:35.079Z,1592847455.079 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-06-22T17:37:35.079Z,1592847455.079 [RDI_Pathfinder] Communications Fault, FailCount= 2 2020-06-22T17:37:35.079Z,1592847455.079 [RDI_Pathfinder](ERROR): Communications Fault 2020-06-22T17:37:35.127Z,1592847455.127 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-06-22T17:37:35.550Z,1592847455.550 [RDI_Pathfinder](INFO): Powering down 2020-06-22T17:37:36.322Z,1592847456.322 [BuoyancyServo](FAULT): Overload Error 2020-06-22T17:37:36.322Z,1592847456.322 [BuoyancyServo] Hardware Fault, FailCount= 2 2020-06-22T17:37:36.322Z,1592847456.322 [BuoyancyServo](ERROR): Hardware Fault 2020-06-22T17:37:36.364Z,1592847456.364 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder 2020-06-22T17:37:36.364Z,1592847456.364 [RDI_Pathfinder] No Fault, FailCount= 2 2020-06-22T17:37:36.364Z,1592847456.364 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2020-06-22T17:37:38.319Z,1592847458.319 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:37:38.319Z,1592847458.319 [BuoyancyServo](INFO): Powering down 2020-06-22T17:37:39.149Z,1592847459.149 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2020-06-22T17:37:39.149Z,1592847459.149 [BuoyancyServo] No Fault, FailCount= 2 2020-06-22T17:37:39.540Z,1592847459.540 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:37:39.566Z,1592847459.566 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:37:49.211Z,1592847469.211 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2020-06-22T17:37:49.211Z,1592847469.211 [DAT] Communications Fault, FailCount= 1 2020-06-22T17:37:49.211Z,1592847469.211 [DAT](ERROR): Communications Fault 2020-06-22T17:37:49.312Z,1592847469.312 [CBIT](ERROR): Communications Fault in component: DAT 2020-06-22T17:37:49.620Z,1592847469.620 [DAT](INFO): Powering down 2020-06-22T17:37:50.507Z,1592847470.507 [CBIT](INFO): Clearing failed state for component DAT 2020-06-22T17:37:50.508Z,1592847470.508 [DAT] No Fault, FailCount= 1 2020-06-22T17:37:52.727Z,1592847472.727 [CommandLine](IMPORTANT): got command failComponent 2020-06-22T17:37:52.728Z,1592847472.728 [CommandLine](IMPORTANT): Failed components: 2020-06-22T17:37:52.728Z,1592847472.728 [CommandLine](IMPORTANT): No failed Components. 2020-06-22T17:37:52.846Z,1592847472.846 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-06-22T17:37:52.846Z,1592847472.846 [DAT](INFO): Powering up 2020-06-22T17:37:52.846Z,1592847472.846 [DAT](DEBUG): Initializing DAT. 2020-06-22T17:37:52.937Z,1592847472.937 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-06-22T17:37:58.904Z,1592847478.904 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2020-06-22T17:37:58.904Z,1592847478.904 [RDI_Pathfinder] Communications Fault, FailCount= 3 2020-06-22T17:37:58.904Z,1592847478.904 [RDI_Pathfinder](ERROR): Communications Fault 2020-06-22T17:37:58.951Z,1592847478.951 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder 2020-06-22T17:37:58.951Z,1592847478.951 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder 2020-06-22T17:37:59.370Z,1592847479.370 [RDI_Pathfinder](INFO): Powering down 2020-06-22T17:37:59.418Z,1592847479.418 [CBIT](INFO): Critical error at 20200622T173758 2020-06-22T17:38:02.590Z,1592847482.590 [BuoyancyServo](FAULT): Overload Error 2020-06-22T17:38:02.590Z,1592847482.590 [BuoyancyServo] Hardware Fault, FailCount= 3 2020-06-22T17:38:02.590Z,1592847482.590 [BuoyancyServo](ERROR): Hardware Fault 2020-06-22T17:38:02.635Z,1592847482.635 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2020-06-22T17:38:04.572Z,1592847484.572 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:38:04.572Z,1592847484.572 [BuoyancyServo](INFO): Powering down 2020-06-22T17:38:04.990Z,1592847484.990 [SBIT](IMPORTANT): Beginning Startup BIT 2020-06-22T17:38:04.999Z,1592847484.999 [CBIT](IMPORTANT): Beginning ground fault scan 2020-06-22T17:38:05.488Z,1592847485.488 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2020-06-22T17:38:05.488Z,1592847485.488 [BuoyancyServo] No Fault, FailCount= 3 2020-06-22T17:38:05.814Z,1592847485.814 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:38:05.815Z,1592847485.815 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:38:07.015Z,1592847487.015 [DAT](INFO): setting local address to 9 2020-06-22T17:38:07.389Z,1592847487.389 [DAT](INFO): set local address to 9 2020-06-22T17:38:16.368Z,1592847496.368 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.002323 CHAN A1 (24V): 0.036489 CHAN A2 (12V): -0.007274 CHAN A3 (5V): -0.002358 CHAN B0 (3.3V): 0.000449 CHAN B1 (3.15aV): 0.000251 CHAN B2 (3.15bV): -0.000060 CHAN B3 (GND): 0.001774 OPEN: -0.000833 Full Scale Calc: 4.765 mA, -1.589 mA 2020-06-22T17:38:25.648Z,1592847505.648 [CommandLine](IMPORTANT): got command configSet RDI_Pathfinder.loadAtStartup 0.000000 bool persist 2020-06-22T17:38:25.648Z,1592847505.648 [CommandLine](IMPORTANT): configSet RDI_Pathfinder.loadAtStartup requires a restart to take effect. 2020-06-22T17:38:28.426Z,1592847508.426 [BuoyancyServo](FAULT): Overload Error 2020-06-22T17:38:28.426Z,1592847508.426 [BuoyancyServo] Hardware Fault, FailCount= 4 2020-06-22T17:38:28.426Z,1592847508.426 [BuoyancyServo](ERROR): Hardware Fault 2020-06-22T17:38:28.496Z,1592847508.496 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2020-06-22T17:38:30.444Z,1592847510.444 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:38:30.444Z,1592847510.444 [BuoyancyServo](INFO): Powering down 2020-06-22T17:38:31.268Z,1592847511.268 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2020-06-22T17:38:31.268Z,1592847511.268 [BuoyancyServo] No Fault, FailCount= 4 2020-06-22T17:38:31.652Z,1592847511.652 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:38:31.653Z,1592847511.653 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:38:54.318Z,1592847534.318 [BuoyancyServo](FAULT): Overload Error 2020-06-22T17:38:54.318Z,1592847534.318 [BuoyancyServo] Hardware Fault, FailCount= 5 2020-06-22T17:38:54.318Z,1592847534.318 [BuoyancyServo](ERROR): Hardware Fault 2020-06-22T17:38:54.384Z,1592847534.384 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2020-06-22T17:38:55.678Z,1592847535.678 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.loadAtStartup 0.000000 bool persist 2020-06-22T17:38:55.678Z,1592847535.678 [CommandLine](IMPORTANT): configSet BuoyancyServo.loadAtStartup requires a restart to take effect. 2020-06-22T17:38:56.280Z,1592847536.280 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:38:56.280Z,1592847536.280 [BuoyancyServo](INFO): Powering down 2020-06-22T17:38:57.123Z,1592847537.123 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2020-06-22T17:38:57.124Z,1592847537.124 [BuoyancyServo] No Fault, FailCount= 5 2020-06-22T17:38:57.492Z,1592847537.492 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-06-22T17:38:57.493Z,1592847537.493 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-06-22T17:38:58.735Z,1592847538.735 [SBIT](IMPORTANT): SBIT PASSED 2020-06-22T17:38:58.736Z,1592847538.736 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-06-22T17:38:58.736Z,1592847538.736 [SBIT](IMPORTANT): BuoyancyServo.loadAtStartup=0 bool; 2020-06-22T17:38:58.736Z,1592847538.736 [SBIT](IMPORTANT): DataOverHttps.verbosity=0 count; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=15 ampere_hour; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2020-06-22T17:38:58.737Z,1592847538.737 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=413.311030 cubic_centimeter; 2020-06-22T17:38:58.738Z,1592847538.738 [SBIT](IMPORTANT): VerticalControl.massDefault=-14.023880 millimeter; 2020-06-22T17:38:58.738Z,1592847538.738 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool; 2020-06-22T17:38:59.144Z,1592847539.144 [MissionManager](IMPORTANT): Started mission Startup 2020-06-22T17:38:59.144Z,1592847539.144 [Startup] Running Loop=1 2020-06-22T17:38:59.144Z,1592847539.144 [Startup](DEBUG): Aggregate::initialize Startup 2020-06-22T17:38:59.144Z,1592847539.144 [Startup:A.GoToSurface] Running Loop=1 2020-06-22T17:38:59.144Z,1592847539.144 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-06-22T17:38:59.145Z,1592847539.145 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-06-22T17:38:59.145Z,1592847539.145 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-06-22T17:38:59.145Z,1592847539.145 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-06-22T17:38:59.146Z,1592847539.146 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-06-22T17:38:59.146Z,1592847539.146 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-06-22T17:38:59.149Z,1592847539.149 [Startup:StartupSatComms] Running Loop=1 2020-06-22T17:38:59.149Z,1592847539.149 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-06-22T17:38:59.149Z,1592847539.149 [Startup:StartupSatComms:A] Running Loop=1 2020-06-22T17:38:59.532Z,1592847539.532 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-06-22T17:39:11.724Z,1592847551.724 [CommandLine](IMPORTANT): got command restart application 2020-06-22T17:39:12.726Z,1592847552.726 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:12.726Z,1592847552.726 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:12.746Z,1592847552.746 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-06-22T17:39:12.746Z,1592847552.746 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:12.747Z,1592847552.747 [CommandLine](INFO): Join timeout helper Thread ID is 4160 2020-06-22T17:39:12.747Z,1592847552.747 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-06-22T17:39:12.747Z,1592847552.747 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:12.748Z,1592847552.748 [NavChartDb](INFO): Join timeout helper Thread ID is 4161 2020-06-22T17:39:13.034Z,1592847553.034 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:13.034Z,1592847553.034 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:13.038Z,1592847553.038 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-06-22T17:39:13.038Z,1592847553.038 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:13.038Z,1592847553.038 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4162 2020-06-22T17:39:13.314Z,1592847553.314 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:13.315Z,1592847553.315 [WetLabsBB2FL](INFO): Powering down 2020-06-22T17:39:13.315Z,1592847553.315 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:13.318Z,1592847553.318 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-06-22T17:39:13.318Z,1592847553.318 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:13.318Z,1592847553.318 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4163 2020-06-22T17:39:14.166Z,1592847554.166 [CTD_Seabird](INFO): Powering down 2020-06-22T17:39:14.178Z,1592847554.178 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:14.178Z,1592847554.178 [CTD_Seabird](INFO): Powering down 2020-06-22T17:39:14.190Z,1592847554.190 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.198Z,1592847554.198 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2020-06-22T17:39:14.198Z,1592847554.198 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.198Z,1592847554.198 [BackSeatDriver](INFO): Join timeout helper Thread ID is 4164 2020-06-22T17:39:14.410Z,1592847554.410 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:14.410Z,1592847554.410 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.419Z,1592847554.419 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-06-22T17:39:14.419Z,1592847554.419 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.419Z,1592847554.419 [Radio_Surface](INFO): Join timeout helper Thread ID is 4165 2020-06-22T17:39:14.810Z,1592847554.810 [Radio_Surface](INFO): Powering down 2020-06-22T17:39:14.811Z,1592847554.811 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:14.811Z,1592847554.811 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.831Z,1592847554.831 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-06-22T17:39:14.831Z,1592847554.831 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:14.831Z,1592847554.831 [Onboard](INFO): Join timeout helper Thread ID is 4166 2020-06-22T17:39:18.686Z,1592847558.686 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:18.686Z,1592847558.686 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:18.691Z,1592847558.691 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-06-22T17:39:18.691Z,1592847558.691 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:18.691Z,1592847558.691 [DataOverHttps](INFO): Join timeout helper Thread ID is 4167 2020-06-22T17:39:18.947Z,1592847558.947 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:5308432 %. Average:530893.062500 2020-06-22T17:39:19.390Z,1592847559.390 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:19.393Z,1592847559.393 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:19.399Z,1592847559.399 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-06-22T17:39:19.399Z,1592847559.399 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:19.399Z,1592847559.399 [logger](INFO): Join timeout helper Thread ID is 4168 2020-06-22T17:39:19.402Z,1592847559.402 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:19.402Z,1592847559.402 [logger ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:19.404Z,1592847559.404 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-06-22T17:39:19.404Z,1592847559.404 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:19.404Z,1592847559.404 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-06-22T17:39:19.404Z,1592847559.404 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:19.404Z,1592847559.404 [controlThread](INFO): Join timeout helper Thread ID is 4169 2020-06-22T17:39:19.690Z,1592847559.690 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-06-22T17:39:19.690Z,1592847559.690 [controlThread](DEBUG): Uninitializing ControlThread 2020-06-22T17:39:19.691Z,1592847559.691 [AHRS_M2](INFO): Powering down 2020-06-22T17:39:19.763Z,1592847559.763 [NAL9602](INFO): Powering down 2020-06-22T17:39:19.764Z,1592847559.764 [RDI_Pathfinder](INFO): Powering down 2020-06-22T17:39:19.764Z,1592847559.764 [DAT](INFO): Powering down 2020-06-22T17:39:19.883Z,1592847559.883 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-06-22T17:39:19.884Z,1592847559.884 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-06-22T17:39:19.884Z,1592847559.884 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-06-22T17:39:19.885Z,1592847559.885 [MissionManager](INFO): Uninitializing Mission Default 2020-06-22T17:39:19.887Z,1592847559.887 [MissionManager](INFO): Uninitializing Mission Startup 2020-06-22T17:39:19.887Z,1592847559.887 [Startup] Stopped 2020-06-22T17:39:19.888Z,1592847559.888 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-06-22T17:39:19.888Z,1592847559.888 [Startup:A.GoToSurface] Stopped 2020-06-22T17:39:19.888Z,1592847559.888 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-06-22T17:39:19.888Z,1592847559.888 [Startup:StartupSatComms] Stopped 2020-06-22T17:39:19.888Z,1592847559.888 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-06-22T17:39:19.888Z,1592847559.888 [Startup:StartupSatComms:A] Stopped 2020-06-22T17:39:19.889Z,1592847559.889 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-06-22T17:39:19.890Z,1592847559.890 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-06-22T17:39:19.890Z,1592847559.890 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-06-22T17:39:19.890Z,1592847559.890 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-06-22T17:39:19.891Z,1592847559.891 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-06-22T17:39:19.891Z,1592847559.891 [BuoyancyServo](INFO): Powering down 2020-06-22T17:39:20.022Z,1592847560.022 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-06-22T17:39:20.022Z,1592847560.022 [ElevatorServo](INFO): Powering down 2020-06-22T17:39:20.023Z,1592847560.023 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-06-22T17:39:20.023Z,1592847560.023 [MassServo](INFO): Powering down 2020-06-22T17:39:20.024Z,1592847560.024 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-06-22T17:39:20.024Z,1592847560.024 [RudderServo](INFO): Powering down 2020-06-22T17:39:20.025Z,1592847560.025 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-06-22T17:39:20.025Z,1592847560.025 [ThrusterServo](INFO): Powering down 2020-06-22T17:39:20.025Z,1592847560.025 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-06-22T17:39:20.026Z,1592847560.026 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-06-22T17:39:20.026Z,1592847560.026 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-06-22T17:39:20.026Z,1592847560.026 [CBIT](DEBUG): Powering off loads. 2020-06-22T17:39:20.038Z,1592847560.038 [CBIT](DEBUG): Disabling WDT. 2020-06-22T17:39:20.050Z,1592847560.050 [CBIT](DEBUG): Opening all GF detection circuits. 2020-06-22T17:39:20.051Z,1592847560.051 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.139Z,1592847560.139 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.141Z,1592847560.141 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.146Z,1592847560.146 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.201Z,1592847560.201 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.203Z,1592847560.203 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.206Z,1592847560.206 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.215Z,1592847560.215 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-06-22T17:39:20.276Z,1592847560.276 [logger ThreadHandler](INFO): Thread cancelled.