2020-05-22T15:08:32.006Z,1590160112.006 [Supervisor](DEBUG): Initializing supervisor. 2020-05-22T15:08:32.009Z,1590160112.009 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-22T15:08:32.009Z,1590160112.009 [SyncHandler](INFO): Protected caller Thread ID is 806 2020-05-22T15:08:32.010Z,1590160112.010 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-22T15:08:32.011Z,1590160112.011 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-22T15:08:32.011Z,1590160112.011 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807 2020-05-22T15:08:32.014Z,1590160112.014 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-22T15:08:32.026Z,1590160112.026 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-22T15:08:32.027Z,1590160112.027 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-22T15:08:32.028Z,1590160112.028 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808 2020-05-22T15:08:32.028Z,1590160112.028 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-22T15:08:32.029Z,1590160112.029 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-22T15:08:32.030Z,1590160112.030 [logger ThreadHandler](INFO): Protected caller Thread ID is 809 2020-05-22T15:08:32.031Z,1590160112.031 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-22T15:08:32.032Z,1590160112.032 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-22T15:08:32.036Z,1590160112.036 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-22T15:08:32.130Z,1590160112.130 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-22T15:08:32.132Z,1590160112.132 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-22T15:08:32.722Z,1590160112.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-22T15:08:32.724Z,1590160112.724 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-05-22T15:08:32.918Z,1590160112.918 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-05-22T15:08:32.918Z,1590160112.918 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-22T15:08:33.018Z,1590160113.018 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-22T15:08:33.018Z,1590160113.018 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-22T15:08:33.119Z,1590160113.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-22T15:08:33.121Z,1590160113.121 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-22T15:08:33.203Z,1590160113.203 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-22T15:08:33.340Z,1590160113.340 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-22T15:08:33.342Z,1590160113.342 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-22T15:08:33.631Z,1590160113.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-22T15:08:33.633Z,1590160113.633 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-22T15:08:34.099Z,1590160114.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-22T15:08:34.101Z,1590160114.101 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-22T15:08:34.243Z,1590160114.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-22T15:08:34.244Z,1590160114.244 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-22T15:08:34.441Z,1590160114.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-22T15:08:34.443Z,1590160114.443 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-22T15:08:34.928Z,1590160114.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-22T15:08:34.929Z,1590160114.929 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-22T15:08:35.146Z,1590160115.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-22T15:08:35.148Z,1590160115.148 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-22T15:08:35.354Z,1590160115.354 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-22T15:08:35.759Z,1590160115.759 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-22T15:08:36.312Z,1590160116.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-22T15:08:36.312Z,1590160116.312 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-22T15:08:36.645Z,1590160116.645 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-22T15:08:36.647Z,1590160116.647 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-05-22T15:08:36.650Z,1590160116.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-05-22T15:08:36.733Z,1590160116.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-05-22T15:08:36.891Z,1590160116.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-05-22T15:08:36.999Z,1590160116.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-05-22T15:08:37.084Z,1590160117.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-05-22T15:08:37.178Z,1590160117.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-05-22T15:08:37.379Z,1590160117.379 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-05-22T15:08:37.606Z,1590160117.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-22T15:08:37.606Z,1590160117.606 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-05-22T15:08:37.699Z,1590160117.699 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-05-22T15:08:37.797Z,1590160117.797 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-05-22T15:08:37.935Z,1590160117.935 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-05-22T15:08:38.033Z,1590160118.033 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-05-22T15:08:38.036Z,1590160118.036 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-05-22T15:08:38.130Z,1590160118.130 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-05-22T15:08:38.131Z,1590160118.131 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-22T15:08:38.195Z,1590160118.195 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-22T15:08:38.303Z,1590160118.303 [VerticalControl] Loaded 2020-05-22T15:08:38.303Z,1590160118.303 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-22T15:08:38.304Z,1590160118.304 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-22T15:08:38.381Z,1590160118.381 [HorizontalControl] Loaded 2020-05-22T15:08:38.381Z,1590160118.381 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-22T15:08:38.382Z,1590160118.382 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-22T15:08:38.387Z,1590160118.387 [SpeedControl] Loaded 2020-05-22T15:08:38.387Z,1590160118.387 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-22T15:08:38.388Z,1590160118.388 [LoopControl](DEBUG): Construct LoopControl. 2020-05-22T15:08:38.388Z,1590160118.388 [LoopControl] Loaded 2020-05-22T15:08:38.389Z,1590160118.389 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-22T15:08:38.389Z,1590160118.389 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-22T15:08:38.390Z,1590160118.390 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-22T15:08:38.444Z,1590160118.444 [DepthRateCalculator] Loaded 2020-05-22T15:08:38.445Z,1590160118.445 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-22T15:08:38.450Z,1590160118.450 [PitchRateCalculator] Loaded 2020-05-22T15:08:38.450Z,1590160118.450 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-22T15:08:38.465Z,1590160118.465 [SpeedCalculator] Loaded 2020-05-22T15:08:38.465Z,1590160118.465 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-22T15:08:38.486Z,1590160118.486 [TempGradientCalculator] Loaded 2020-05-22T15:08:38.486Z,1590160118.486 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-22T15:08:38.491Z,1590160118.491 [YawRateCalculator] Loaded 2020-05-22T15:08:38.491Z,1590160118.491 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-22T15:08:38.520Z,1590160118.520 [ElevatorOffsetCalculator] Loaded 2020-05-22T15:08:38.520Z,1590160118.520 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-22T15:08:38.520Z,1590160118.520 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-22T15:08:38.522Z,1590160118.522 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-22T15:08:38.563Z,1590160118.563 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-22T15:08:38.563Z,1590160118.563 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-22T15:08:38.686Z,1590160118.686 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-22T15:08:38.687Z,1590160118.687 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-22T15:08:39.062Z,1590160119.062 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-22T15:08:39.063Z,1590160119.063 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-22T15:08:39.184Z,1590160119.184 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-22T15:08:39.184Z,1590160119.184 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-22T15:08:39.719Z,1590160119.719 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-05-22T15:08:39.724Z,1590160119.724 [AHRS_M2](INFO): created writer for : platform_orientation 2020-05-22T15:08:39.726Z,1590160119.726 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-05-22T15:08:39.731Z,1590160119.731 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-05-22T15:08:39.731Z,1590160119.731 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-05-22T15:08:39.736Z,1590160119.736 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-05-22T15:08:39.737Z,1590160119.737 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-05-22T15:08:39.742Z,1590160119.742 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-05-22T15:08:39.916Z,1590160119.916 [AHRS_M2] Loaded 2020-05-22T15:08:39.916Z,1590160119.916 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-05-22T15:08:40.063Z,1590160120.063 [DataOverHttps] Loaded 2020-05-22T15:08:40.063Z,1590160120.063 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-22T15:08:40.064Z,1590160120.064 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409044E0 2020-05-22T15:08:40.064Z,1590160120.064 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 892 2020-05-22T15:08:40.078Z,1590160120.078 [Depth_Keller] Loaded 2020-05-22T15:08:40.078Z,1590160120.078 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-22T15:08:40.083Z,1590160120.083 [DropWeight] Loaded 2020-05-22T15:08:40.083Z,1590160120.083 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-05-22T15:08:40.176Z,1590160120.176 [NAL9602] Loaded 2020-05-22T15:08:40.177Z,1590160120.177 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-22T15:08:40.212Z,1590160120.212 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 2020-05-22T15:08:40.213Z,1590160120.213 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 2020-05-22T15:08:40.213Z,1590160120.213 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 2020-05-22T15:08:40.214Z,1590160120.214 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 2020-05-22T15:08:40.214Z,1590160120.214 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 2020-05-22T15:08:40.215Z,1590160120.215 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 2020-05-22T15:08:40.215Z,1590160120.215 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 2020-05-22T15:08:40.216Z,1590160120.216 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 2020-05-22T15:08:40.216Z,1590160120.216 [Onboard] Loaded 2020-05-22T15:08:40.216Z,1590160120.216 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-22T15:08:40.219Z,1590160120.219 [Radio_Surface] Loaded 2020-05-22T15:08:40.220Z,1590160120.220 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-22T15:08:40.220Z,1590160120.220 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409344E0 2020-05-22T15:08:40.221Z,1590160120.221 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 893 2020-05-22T15:08:40.263Z,1590160120.263 [RDI_Pathfinder] Loaded 2020-05-22T15:08:40.263Z,1590160120.263 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-05-22T15:08:40.392Z,1590160120.392 [DAT] Loaded 2020-05-22T15:08:40.393Z,1590160120.393 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-22T15:08:41.850Z,1590160121.850 [BPC1] Loaded 2020-05-22T15:08:41.850Z,1590160121.850 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-22T15:08:41.851Z,1590160121.851 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-22T15:08:41.852Z,1590160121.852 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-22T15:08:42.241Z,1590160122.241 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-22T15:08:42.241Z,1590160122.241 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-22T15:08:42.292Z,1590160122.292 [NavChart] Loaded 2020-05-22T15:08:42.293Z,1590160122.293 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-22T15:08:42.300Z,1590160122.300 [UniversalFixResidualReporter] Loaded 2020-05-22T15:08:42.301Z,1590160122.301 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-22T15:08:42.301Z,1590160122.301 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-22T15:08:42.302Z,1590160122.302 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-22T15:08:42.678Z,1590160122.678 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-22T15:08:42.689Z,1590160122.689 [SBIT] Loaded 2020-05-22T15:08:42.689Z,1590160122.689 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-22T15:08:42.690Z,1590160122.690 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-22T15:08:42.703Z,1590160122.703 [IBIT] Loaded 2020-05-22T15:08:42.703Z,1590160122.703 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-22T15:08:42.706Z,1590160122.706 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-22T15:08:42.840Z,1590160122.840 [CBIT] Loaded 2020-05-22T15:08:42.841Z,1590160122.841 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-22T15:08:42.841Z,1590160122.841 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-22T15:08:42.842Z,1590160122.842 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-22T15:08:42.968Z,1590160122.968 [BuoyancyServo] Loaded 2020-05-22T15:08:42.968Z,1590160122.968 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-22T15:08:42.980Z,1590160122.980 [ElevatorServo] Loaded 2020-05-22T15:08:42.980Z,1590160122.980 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-22T15:08:42.991Z,1590160122.991 [MassServo] Loaded 2020-05-22T15:08:42.991Z,1590160122.991 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-22T15:08:43.002Z,1590160123.002 [RudderServo] Loaded 2020-05-22T15:08:43.002Z,1590160123.002 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-22T15:08:43.012Z,1590160123.012 [ThrusterServo] Loaded 2020-05-22T15:08:43.013Z,1590160123.013 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-22T15:08:43.013Z,1590160123.013 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-22T15:08:43.014Z,1590160123.014 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-22T15:08:43.044Z,1590160123.044 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-22T15:08:43.044Z,1590160123.044 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-22T15:08:43.341Z,1590160123.341 [BackSeatDriver] Loaded 2020-05-22T15:08:43.341Z,1590160123.341 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-05-22T15:08:43.342Z,1590160123.342 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0 2020-05-22T15:08:43.343Z,1590160123.343 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 894 2020-05-22T15:08:43.352Z,1590160123.352 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-05-22T15:08:43.357Z,1590160123.357 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-05-22T15:08:43.358Z,1590160123.358 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-05-22T15:08:43.362Z,1590160123.362 [CTD_Seabird](INFO): created writer for : depth 2020-05-22T15:08:43.363Z,1590160123.363 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-05-22T15:08:43.367Z,1590160123.367 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-05-22T15:08:43.368Z,1590160123.368 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-05-22T15:08:43.373Z,1590160123.373 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-05-22T15:08:43.374Z,1590160123.374 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-05-22T15:08:43.378Z,1590160123.378 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-05-22T15:08:43.379Z,1590160123.379 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-05-22T15:08:43.384Z,1590160123.384 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-05-22T15:08:43.385Z,1590160123.385 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-05-22T15:08:43.390Z,1590160123.390 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-05-22T15:08:43.417Z,1590160123.417 [CTD_Seabird] Loaded 2020-05-22T15:08:43.417Z,1590160123.417 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-05-22T15:08:43.418Z,1590160123.418 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0 2020-05-22T15:08:43.418Z,1590160123.418 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 895 2020-05-22T15:08:43.425Z,1590160123.425 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-22T15:08:43.425Z,1590160123.425 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-22T15:08:43.429Z,1590160123.429 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-22T15:08:43.429Z,1590160123.429 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-22T15:08:43.433Z,1590160123.433 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-22T15:08:43.433Z,1590160123.433 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-22T15:08:43.437Z,1590160123.437 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-22T15:08:43.438Z,1590160123.438 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-22T15:08:43.442Z,1590160123.442 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-22T15:08:43.442Z,1590160123.442 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-22T15:08:43.446Z,1590160123.446 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-22T15:08:43.446Z,1590160123.446 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-22T15:08:43.450Z,1590160123.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-22T15:08:43.450Z,1590160123.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-22T15:08:43.454Z,1590160123.454 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.459Z,1590160123.459 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.459Z,1590160123.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.459Z,1590160123.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.464Z,1590160123.464 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.464Z,1590160123.464 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.468Z,1590160123.468 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.468Z,1590160123.468 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-22T15:08:43.472Z,1590160123.472 [WetLabsBB2FL] Loaded 2020-05-22T15:08:43.472Z,1590160123.472 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-22T15:08:43.473Z,1590160123.473 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B2A4E0 2020-05-22T15:08:43.474Z,1590160123.474 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 896 2020-05-22T15:08:43.707Z,1590160123.707 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-05-22T15:08:43.708Z,1590160123.708 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-05-22T15:08:43.709Z,1590160123.709 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-05-22T15:08:43.713Z,1590160123.713 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2020-05-22T15:08:43.713Z,1590160123.713 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-05-22T15:08:43.713Z,1590160123.713 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-05-22T15:08:43.718Z,1590160123.718 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-05-22T15:08:43.718Z,1590160123.718 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-05-22T15:08:43.723Z,1590160123.723 [WetLabsUBAT] Loaded 2020-05-22T15:08:43.723Z,1590160123.723 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-05-22T15:08:43.724Z,1590160123.724 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B5A4E0 2020-05-22T15:08:43.725Z,1590160123.725 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 897 2020-05-22T15:08:43.725Z,1590160123.725 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-22T15:08:43.729Z,1590160123.729 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-22T15:08:43.730Z,1590160123.730 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-22T15:08:43.736Z,1590160123.736 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-22T15:08:43.737Z,1590160123.737 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B8A4E0 2020-05-22T15:08:43.737Z,1590160123.737 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 898 2020-05-22T15:08:43.742Z,1590160123.742 [Supervisor](INFO): Main Thread ID is 802 2020-05-22T15:08:43.742Z,1590160123.742 [Supervisor](DEBUG): Running supervisor. 2020-05-22T15:08:43.743Z,1590160123.743 [CommandLine ThreadHandler](INFO): Handler Thread ID is 899 2020-05-22T15:08:43.745Z,1590160123.745 [controlThread ThreadHandler](INFO): Handler Thread ID is 900 2020-05-22T15:08:43.746Z,1590160123.746 [controlThread](DEBUG): Initializing ControlThread 2020-05-22T15:08:43.747Z,1590160123.747 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-22T15:08:43.748Z,1590160123.748 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-22T15:08:43.749Z,1590160123.749 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-22T15:08:43.749Z,1590160123.749 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-22T15:08:43.750Z,1590160123.750 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-22T15:08:43.750Z,1590160123.750 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-22T15:08:43.750Z,1590160123.750 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-22T15:08:43.750Z,1590160123.750 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-22T15:08:43.751Z,1590160123.751 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-22T15:08:43.751Z,1590160123.751 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-22T15:08:43.758Z,1590160123.758 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-22T15:08:43.758Z,1590160123.758 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-22T15:08:43.758Z,1590160123.758 [SBIT](INFO): Initialize SBIT Component. 2020-05-22T15:08:43.759Z,1590160123.759 [SBIT](IMPORTANT): git: 2020-05-06_C 2020-05-22T15:08:43.759Z,1590160123.759 [SBIT](INFO): git hash: 0cf8c4e35deb3fff8b463df734a916cd7b4cfdba 2020-05-22T15:08:43.759Z,1590160123.759 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-22T15:08:43.759Z,1590160123.759 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2020-05-22T15:08:43.760Z,1590160123.760 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2020-05-22T15:08:43.761Z,1590160123.761 [IBIT](INFO): Initialize IBIT Component. 2020-05-22T15:08:43.762Z,1590160123.762 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-22T15:08:43.763Z,1590160123.763 [logger ThreadHandler](INFO): Handler Thread ID is 901 2020-05-22T15:08:43.773Z,1590160123.773 [CBIT](DEBUG): Initialized mux pins. 2020-05-22T15:08:43.774Z,1590160123.774 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2020-05-22T15:08:43.774Z,1590160123.774 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-22T15:08:43.782Z,1590160123.782 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 902 2020-05-22T15:08:43.783Z,1590160123.783 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-22T15:08:43.794Z,1590160123.794 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 903 2020-05-22T15:08:43.797Z,1590160123.797 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2020-05-22T15:08:43.798Z,1590160123.798 [CBIT](DEBUG): Initializing heartbeat. 2020-05-22T15:08:43.806Z,1590160123.806 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 904 2020-05-22T15:08:43.814Z,1590160123.814 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 905 2020-05-22T15:08:43.814Z,1590160123.814 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-05-22T15:08:43.819Z,1590160123.819 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 907 2020-05-22T15:08:43.820Z,1590160123.820 [WetLabsBB2FL](INFO): Powering down 2020-05-22T15:08:43.850Z,1590160123.850 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 908 2020-05-22T15:08:43.862Z,1590160123.862 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 909 2020-05-22T15:08:43.869Z,1590160123.869 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-22T15:08:43.869Z,1590160123.869 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-22T15:08:43.871Z,1590160123.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-22T15:08:43.871Z,1590160123.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-22T15:08:43.871Z,1590160123.871 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-22T15:08:43.871Z,1590160123.871 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-22T15:08:43.872Z,1590160123.872 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-22T15:08:43.873Z,1590160123.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-22T15:08:43.873Z,1590160123.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-22T15:08:43.873Z,1590160123.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-22T15:08:43.873Z,1590160123.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-22T15:08:43.873Z,1590160123.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-22T15:08:43.874Z,1590160123.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-22T15:08:43.905Z,1590160123.905 [CBIT](DEBUG): Backplane powered. 2020-05-22T15:08:43.907Z,1590160123.907 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-22T15:08:43.935Z,1590160123.935 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T15:08:43.970Z,1590160123.970 [MissionManager](DEBUG): 2020-05-22T15:08:43.971Z,1590160123.971 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-22T15:08:44.046Z,1590160124.046 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-22T15:08:44.047Z,1590160124.047 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-22T15:08:44.049Z,1590160124.049 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-22T15:08:44.095Z,1590160124.095 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-22T15:08:44.097Z,1590160124.097 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-22T15:08:44.115Z,1590160124.115 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-22T15:08:44.124Z,1590160124.124 [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-05-22T15:08:44.145Z,1590160124.145 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,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-05-22T15:08:44.178Z,1590160124.178 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-05-22T15:08:44.198Z,1590160124.198 [Radio_Surface](INFO): Powering up 2020-05-22T15:08:44.299Z,1590160124.299 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-05-22T15:08:44.324Z,1590160124.324 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-22T15:08:44.324Z,1590160124.324 [DAT](INFO): Powering up 2020-05-22T15:08:44.325Z,1590160124.325 [DAT](DEBUG): Initializing DAT. 2020-05-22T15:08:44.350Z,1590160124.350 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-22T15:08:44.410Z,1590160124.410 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T15:08:44.460Z,1590160124.460 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-22T15:08:44.466Z,1590160124.466 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-22T15:08:44.467Z,1590160124.467 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-22T15:08:44.474Z,1590160124.474 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-22T15:08:44.475Z,1590160124.475 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-22T15:08:44.482Z,1590160124.482 [MassServo](DEBUG): Initializing MassServo. 2020-05-22T15:08:44.483Z,1590160124.483 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-22T15:08:44.490Z,1590160124.490 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-22T15:08:44.491Z,1590160124.491 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-22T15:08:44.498Z,1590160124.498 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-22T15:08:44.500Z,1590160124.500 [CommandLine](FAULT): Scheduling is paused 2020-05-22T15:08:44.501Z,1590160124.501 [CBIT](INFO): Critical error at 20200522T150843 2020-05-22T15:08:44.501Z,1590160124.501 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-05-22T15:08:44.574Z,1590160124.574 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-05-22T15:08:44.574Z,1590160124.574 [DropWeight] Hardware Fault, FailCount= 1 2020-05-22T15:08:44.574Z,1590160124.574 [DropWeight](ERROR): Hardware Fault 2020-05-22T15:08:44.622Z,1590160124.622 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-05-22T15:08:44.633Z,1590160124.633 [CBIT](INFO): Critical error at 20200522T150844 2020-05-22T15:08:44.635Z,1590160124.635 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-05-22T15:08:44.636Z,1590160124.636 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-05-22T15:08:44.986Z,1590160124.986 [CBIT](INFO): Critical error at 20200522T150844 2020-05-22T15:08:54.746Z,1590160134.746 [DAT](DEBUG): 2020-05-22T15:08:54.746Z,1590160134.746 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-05-22T15:08:55.991Z,1590160135.991 [DAT](DEBUG): MF Frequency Band 2020-05-22T15:08:55.992Z,1590160135.992 [DAT](DEBUG): Directional Acoustic Transponder version 8.12.21 2020-05-22T15:08:55.992Z,1590160135.992 [DAT](DEBUG): May 22 2020 15:04:57 2020-05-22T15:08:56.767Z,1590160136.767 [DAT](DEBUG): Features enabled [Bearing] 2020-05-22T15:08:56.768Z,1590160136.768 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-05-22T15:08:56.768Z,1590160136.768 [DAT](INFO): commRate: 800 2020-05-22T15:08:57.182Z,1590160137.182 [DAT](INFO): entering command mode 2020-05-22T15:08:57.575Z,1590160137.575 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T15:08:57.970Z,1590160137.970 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T15:08:58.385Z,1590160138.385 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-22T15:08:58.385Z,1590160138.385 [DAT](INFO): command mode acknowledged 2020-05-22T15:08:58.394Z,1590160138.394 [DAT](INFO): setting verbose to 3 2020-05-22T15:08:58.787Z,1590160138.787 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-05-22T15:08:58.787Z,1590160138.787 [DAT](INFO): set verbose to 3 2020-05-22T15:08:59.211Z,1590160139.211 [DAT](INFO): setting transmit power to 8 2020-05-22T15:08:59.585Z,1590160139.585 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T15:08:59.991Z,1590160139.991 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-22T15:08:59.992Z,1590160139.992 [DAT](INFO): set transmit power to 8 2020-05-22T15:09:00.393Z,1590160140.393 [DAT](INFO): setting local address to 9 2020-05-22T15:09:00.822Z,1590160140.822 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-22T15:09:01.202Z,1590160141.202 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-22T15:09:01.203Z,1590160141.203 [DAT](INFO): set local address to 9 2020-05-22T15:09:10.479Z,1590160150.479 [NAL9602](INFO): Powering up NAL9602 2020-05-22T15:09:21.387Z,1590160161.387 [NAL9602](INFO): NAL9602 initialized 2020-05-22T15:09:23.464Z,1590160163.464 [SBIT](IMPORTANT): Beginning Startup BIT 2020-05-22T15:09:23.472Z,1590160163.472 [CBIT](IMPORTANT): Beginning ground fault scan 2020-05-22T15:09:34.541Z,1590160174.541 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.000328 CHAN A1 (24V): 0.035632 CHAN A2 (12V): -0.007515 CHAN A3 (5V): -0.002389 CHAN B0 (3.3V): -0.000287 CHAN B1 (3.15aV): -0.000543 CHAN B2 (3.15bV): -0.000487 CHAN B3 (GND): 0.001107 OPEN: 0.005008 Full Scale Calc: 4.765 mA, -1.589 mA 2020-05-22T15:10:17.102Z,1590160217.102 [SBIT](IMPORTANT): SBIT PASSED 2020-05-22T15:10:17.102Z,1590160217.102 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2020-05-22T15:10:17.506Z,1590160217.506 [MissionManager](IMPORTANT): Started mission Startup 2020-05-22T15:10:17.507Z,1590160217.507 [Startup] Running Loop=1 2020-05-22T15:10:17.507Z,1590160217.507 [Startup](DEBUG): Aggregate::initialize Startup 2020-05-22T15:10:17.507Z,1590160217.507 [Startup:A.GoToSurface] Running Loop=1 2020-05-22T15:10:17.507Z,1590160217.507 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-22T15:10:17.508Z,1590160217.508 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-22T15:10:17.508Z,1590160217.508 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-22T15:10:17.508Z,1590160217.508 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-22T15:10:17.509Z,1590160217.509 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-22T15:10:17.509Z,1590160217.509 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-22T15:10:17.511Z,1590160217.511 [Startup:StartupSatComms] Running Loop=1 2020-05-22T15:10:17.511Z,1590160217.511 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-05-22T15:10:17.511Z,1590160217.511 [Startup:StartupSatComms:A] Running Loop=1 2020-05-22T15:10:17.920Z,1590160217.920 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-05-22T15:11:09.066Z,1590160269.066 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.011845 2020-05-22T15:11:17.675Z,1590160277.675 [Startup:StartupSatComms:A](INFO): Timed out from 2020-05-22T15:10:17.5Z 2020-05-22T15:11:17.675Z,1590160277.675 [Startup:StartupSatComms:A] Stopped 2020-05-22T15:11:17.675Z,1590160277.675 [Startup:StartupSatComms:B] Running Loop=1 2020-05-22T15:11:18.123Z,1590160278.123 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-05-22T15:11:30.802Z,1590160290.802 [DataOverHttps](INFO): Sending 2805 bytes from file Logs/20200522T145901/Courier0004.lzma 2020-05-22T15:11:31.799Z,1590160291.799 [DataOverHttps](INFO): Moved sent file to Logs/20200522T145901/Courier0004.lzma.bak 2020-05-22T15:11:31.799Z,1590160291.799 [DataOverHttps](INFO): SBD MOMSN=12354694 2020-05-22T15:11:43.929Z,1590160303.929 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-05-22T15:11:43.929Z,1590160303.929 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-05-22T15:11:43.985Z,1590160303.985 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-05-22T15:11:44.392Z,1590160304.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-05-22T15:11:44.392Z,1590160304.392 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-05-22T15:11:47.949Z,1590160307.949 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20200522T150831/Courier0000.lzma 2020-05-22T15:11:48.954Z,1590160308.954 [DataOverHttps](INFO): Moved sent file to Logs/20200522T150831/Courier0000.lzma.bak 2020-05-22T15:11:48.955Z,1590160308.955 [DataOverHttps](INFO): SBD MOMSN=12355536 2020-05-22T15:12:05.029Z,1590160325.029 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20200522T145901/Express0005.lzma 2020-05-22T15:12:06.031Z,1590160326.031 [DataOverHttps](INFO): Moved sent file to Logs/20200522T145901/Express0005.lzma.bak 2020-05-22T15:12:06.031Z,1590160326.031 [DataOverHttps](INFO): SBD MOMSN=12355542 2020-05-22T15:12:17.891Z,1590160337.891 [Startup:StartupSatComms:B](INFO): Timed out from 2020-05-22T15:11:17.7Z 2020-05-22T15:12:17.891Z,1590160337.891 [Startup:StartupSatComms:B] Stopped 2020-05-22T15:12:17.891Z,1590160337.891 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-05-22T15:12:17.891Z,1590160337.891 [Startup:StartupSatComms] Stopped 2020-05-22T15:12:17.891Z,1590160337.891 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-05-22T15:12:17.892Z,1590160337.892 [Startup](INFO): Completed Startup 2020-05-22T15:12:17.892Z,1590160337.892 [MissionManager](INFO): Startup is completed. 2020-05-22T15:12:17.892Z,1590160337.892 [MissionManager](INFO): Uninitializing Mission Startup 2020-05-22T15:12:17.892Z,1590160337.892 [Startup] Stopped 2020-05-22T15:12:17.892Z,1590160337.892 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-05-22T15:12:17.892Z,1590160337.892 [Startup:A.GoToSurface] Stopped 2020-05-22T15:12:17.893Z,1590160337.893 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-22T15:12:18.431Z,1590160338.431 [MissionManager](IMPORTANT): Started mission Default 2020-05-22T15:12:18.431Z,1590160338.431 [Default] Running Loop=1 2020-05-22T15:12:18.431Z,1590160338.431 [Default](DEBUG): Aggregate::initialize Default 2020-05-22T15:12:18.431Z,1590160338.431 [Default:B.GoToSurface] Running Loop=1 2020-05-22T15:12:18.431Z,1590160338.431 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-22T15:12:18.431Z,1590160338.431 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-22T15:12:18.432Z,1590160338.432 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-22T15:12:18.432Z,1590160338.432 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-22T15:12:18.432Z,1590160338.432 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-22T15:12:18.433Z,1590160338.433 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-22T15:12:18.433Z,1590160338.433 [Default:A.Wait] Running Loop=1 2020-05-22T15:12:18.433Z,1590160338.433 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-05-22T15:12:22.126Z,1590160342.126 [DataOverHttps](INFO): Sending 675 bytes from file Logs/20200522T150831/Express000