2019-05-06T23:55:06.788Z,1557186906.788 [Supervisor](DEBUG): Initializing supervisor.
2019-05-06T23:55:06.791Z,1557186906.791 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-05-06T23:55:06.791Z,1557186906.791 [SyncHandler](INFO): Protected caller Thread ID is 1151
2019-05-06T23:55:06.791Z,1557186906.791 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-05-06T23:55:06.792Z,1557186906.792 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-05-06T23:55:06.793Z,1557186906.793 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1152
2019-05-06T23:55:06.796Z,1557186906.796 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-05-06T23:55:06.808Z,1557186906.808 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-05-06T23:55:06.809Z,1557186906.809 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-05-06T23:55:06.809Z,1557186906.809 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1153
2019-05-06T23:55:06.810Z,1557186906.810 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-05-06T23:55:06.811Z,1557186906.811 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-05-06T23:55:06.811Z,1557186906.811 [logger ThreadHandler](INFO): Protected caller Thread ID is 1154
2019-05-06T23:55:06.813Z,1557186906.813 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-05-06T23:55:06.814Z,1557186906.814 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-05-06T23:55:06.815Z,1557186906.815 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-05-06T23:55:06.912Z,1557186906.912 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-05-06T23:55:06.913Z,1557186906.913 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-05-06T23:55:07.113Z,1557186907.113 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-05-06T23:55:07.114Z,1557186907.114 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-05-06T23:55:07.250Z,1557186907.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-05-06T23:55:07.251Z,1557186907.251 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-05-06T23:55:07.781Z,1557186907.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-05-06T23:55:07.781Z,1557186907.781 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-05-06T23:55:08.227Z,1557186908.227 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-05-06T23:55:08.227Z,1557186908.227 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-05-06T23:55:08.689Z,1557186908.689 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-05-06T23:55:08.690Z,1557186908.690 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-05-06T23:55:08.994Z,1557186908.994 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-05-06T23:55:08.995Z,1557186908.995 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-05-06T23:55:09.323Z,1557186909.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-05-06T23:55:09.324Z,1557186909.324 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-05-06T23:55:09.719Z,1557186909.719 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-05-06T23:55:09.719Z,1557186909.719 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-05-06T23:55:09.870Z,1557186909.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-05-06T23:55:09.871Z,1557186909.871 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-05-06T23:55:09.977Z,1557186909.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-05-06T23:55:09.978Z,1557186909.978 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-05-06T23:55:10.060Z,1557186910.060 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-05-06T23:55:10.163Z,1557186910.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-05-06T23:55:10.163Z,1557186910.163 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-05-06T23:55:10.430Z,1557186910.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-05-06T23:55:10.431Z,1557186910.431 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-05-06T23:55:10.640Z,1557186910.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-05-06T23:55:10.642Z,1557186910.642 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-05-06T23:55:10.643Z,1557186910.643 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-05-06T23:55:10.727Z,1557186910.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-05-06T23:55:10.953Z,1557186910.953 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-05-06T23:55:10.954Z,1557186910.954 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-05-06T23:55:11.050Z,1557186911.050 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-05-06T23:55:11.210Z,1557186911.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-05-06T23:55:11.413Z,1557186911.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-05-06T23:55:11.531Z,1557186911.531 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-05-06T23:55:11.839Z,1557186911.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-05-06T23:55:12.109Z,1557186912.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-05-06T23:55:12.264Z,1557186912.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-05-06T23:55:12.370Z,1557186912.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-05-06T23:55:12.469Z,1557186912.469 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-05-06T23:55:12.469Z,1557186912.469 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-05-06T23:55:12.473Z,1557186912.473 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-05-06T23:55:12.596Z,1557186912.596 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-05-06T23:55:12.596Z,1557186912.596 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-05-06T23:55:12.723Z,1557186912.723 [BuoyancyServo] Loaded
2019-05-06T23:55:12.723Z,1557186912.723 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-05-06T23:55:12.738Z,1557186912.738 [ElevatorServo] Loaded
2019-05-06T23:55:12.738Z,1557186912.738 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-05-06T23:55:12.752Z,1557186912.752 [MassServo] Loaded
2019-05-06T23:55:12.752Z,1557186912.752 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-05-06T23:55:12.767Z,1557186912.767 [RudderServo] Loaded
2019-05-06T23:55:12.767Z,1557186912.767 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-05-06T23:55:12.781Z,1557186912.781 [ThrusterServo] Loaded
2019-05-06T23:55:12.782Z,1557186912.782 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-05-06T23:55:12.782Z,1557186912.782 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-05-06T23:55:12.783Z,1557186912.783 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-05-06T23:55:12.935Z,1557186912.935 [SBIT](DEBUG): Construct Startup Built In Test.
2019-05-06T23:55:12.964Z,1557186912.964 [SBIT] Loaded
2019-05-06T23:55:12.964Z,1557186912.964 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-05-06T23:55:12.965Z,1557186912.965 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-05-06T23:55:12.991Z,1557186912.991 [IBIT] Loaded
2019-05-06T23:55:12.991Z,1557186912.991 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-05-06T23:55:12.994Z,1557186912.994 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-05-06T23:55:13.371Z,1557186913.371 [CBIT] Loaded
2019-05-06T23:55:13.371Z,1557186913.371 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-05-06T23:55:13.372Z,1557186913.372 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-05-06T23:55:13.372Z,1557186913.372 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-05-06T23:55:13.622Z,1557186913.622 [Aanderaa_O2] Loaded
2019-05-06T23:55:13.622Z,1557186913.622 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-05-06T23:55:13.632Z,1557186913.632 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-05-06T23:55:13.637Z,1557186913.637 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-05-06T23:55:13.639Z,1557186913.639 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-05-06T23:55:13.644Z,1557186913.644 [CTD_Seabird](INFO): created writer for : depth
2019-05-06T23:55:13.644Z,1557186913.644 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-05-06T23:55:13.649Z,1557186913.649 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-05-06T23:55:13.650Z,1557186913.650 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-05-06T23:55:13.655Z,1557186913.655 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-05-06T23:55:13.655Z,1557186913.655 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-05-06T23:55:13.660Z,1557186913.660 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-05-06T23:55:13.661Z,1557186913.661 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-05-06T23:55:13.666Z,1557186913.666 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-05-06T23:55:13.666Z,1557186913.666 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-05-06T23:55:13.671Z,1557186913.671 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-05-06T23:55:13.697Z,1557186913.697 [CTD_Seabird] Loaded
2019-05-06T23:55:13.697Z,1557186913.697 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-05-06T23:55:13.698Z,1557186913.698 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406624E0
2019-05-06T23:55:13.698Z,1557186913.698 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1234
2019-05-06T23:55:13.724Z,1557186913.724 [ESPComponent] Loaded
2019-05-06T23:55:13.724Z,1557186913.724 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-05-06T23:55:13.738Z,1557186913.738 [PAR_Licor] Loaded
2019-05-06T23:55:13.738Z,1557186913.738 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-05-06T23:55:13.784Z,1557186913.784 [WetLabsBB2FL] Loaded
2019-05-06T23:55:13.784Z,1557186913.784 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-05-06T23:55:13.785Z,1557186913.785 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406924E0
2019-05-06T23:55:13.786Z,1557186913.786 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1235
2019-05-06T23:55:13.786Z,1557186913.786 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-05-06T23:55:13.787Z,1557186913.787 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-05-06T23:55:13.839Z,1557186913.839 [DepthRateCalculator] Loaded
2019-05-06T23:55:13.840Z,1557186913.840 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-05-06T23:55:13.845Z,1557186913.845 [PitchRateCalculator] Loaded
2019-05-06T23:55:13.846Z,1557186913.846 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-05-06T23:55:13.861Z,1557186913.861 [SpeedCalculator] Loaded
2019-05-06T23:55:13.861Z,1557186913.861 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-05-06T23:55:13.882Z,1557186913.882 [TempGradientCalculator] Loaded
2019-05-06T23:55:13.882Z,1557186913.882 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-05-06T23:55:13.888Z,1557186913.888 [YawRateCalculator] Loaded
2019-05-06T23:55:13.888Z,1557186913.888 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-05-06T23:55:13.919Z,1557186913.919 [ElevatorOffsetCalculator] Loaded
2019-05-06T23:55:13.920Z,1557186913.920 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-05-06T23:55:13.920Z,1557186913.920 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-05-06T23:55:13.921Z,1557186913.921 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-05-06T23:55:13.961Z,1557186913.961 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-05-06T23:55:13.961Z,1557186913.961 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-05-06T23:55:14.078Z,1557186914.078 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-05-06T23:55:14.078Z,1557186914.078 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-05-06T23:55:14.099Z,1557186914.099 [NavChart] Loaded
2019-05-06T23:55:14.099Z,1557186914.099 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-05-06T23:55:14.103Z,1557186914.103 [UniversalFixResidualReporter] Loaded
2019-05-06T23:55:14.103Z,1557186914.103 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-05-06T23:55:14.104Z,1557186914.104 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-05-06T23:55:14.104Z,1557186914.104 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-05-06T23:55:14.483Z,1557186914.483 [AcousticModem_Benthos_ATM900] Loaded
2019-05-06T23:55:14.484Z,1557186914.484 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-05-06T23:55:14.557Z,1557186914.557 [DataOverHttps] Loaded
2019-05-06T23:55:14.558Z,1557186914.558 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-05-06T23:55:14.559Z,1557186914.559 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409694E0
2019-05-06T23:55:14.559Z,1557186914.559 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1236
2019-05-06T23:55:14.572Z,1557186914.572 [Depth_Keller] Loaded
2019-05-06T23:55:14.572Z,1557186914.572 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-05-06T23:55:14.659Z,1557186914.659 [NAL9602] Loaded
2019-05-06T23:55:14.660Z,1557186914.660 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-05-06T23:55:14.665Z,1557186914.665 [Onboard] Loaded
2019-05-06T23:55:14.665Z,1557186914.665 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-05-06T23:55:14.668Z,1557186914.668 [Radio_Surface] Loaded
2019-05-06T23:55:14.669Z,1557186914.669 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-05-06T23:55:14.670Z,1557186914.670 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409994E0
2019-05-06T23:55:14.670Z,1557186914.670 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1237
2019-05-06T23:55:14.716Z,1557186914.716 [PNI_TCM] Loaded
2019-05-06T23:55:14.716Z,1557186914.716 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2019-05-06T23:55:15.955Z,1557186915.955 [BPC1] Loaded
2019-05-06T23:55:15.956Z,1557186915.956 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-05-06T23:55:15.956Z,1557186915.956 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-05-06T23:55:15.957Z,1557186915.957 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-05-06T23:55:16.065Z,1557186916.065 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-05-06T23:55:16.066Z,1557186916.066 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-05-06T23:55:16.149Z,1557186916.149 [VerticalControl](DEBUG): Construct VerticalControl.
2019-05-06T23:55:16.231Z,1557186916.231 [VerticalControl] Loaded
2019-05-06T23:55:16.231Z,1557186916.231 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-05-06T23:55:16.232Z,1557186916.232 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-05-06T23:55:16.288Z,1557186916.288 [HorizontalControl] Loaded
2019-05-06T23:55:16.289Z,1557186916.289 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-05-06T23:55:16.289Z,1557186916.289 [SpeedControl](DEBUG): Construct SpeedControl.
2019-05-06T23:55:16.291Z,1557186916.291 [SpeedControl] Loaded
2019-05-06T23:55:16.291Z,1557186916.291 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-05-06T23:55:16.292Z,1557186916.292 [LoopControl](DEBUG): Construct LoopControl.
2019-05-06T23:55:16.292Z,1557186916.292 [LoopControl] Loaded
2019-05-06T23:55:16.293Z,1557186916.293 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-05-06T23:55:16.293Z,1557186916.293 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-05-06T23:55:16.294Z,1557186916.294 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-05-06T23:55:16.317Z,1557186916.317 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-05-06T23:55:16.317Z,1557186916.317 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-05-06T23:55:16.648Z,1557186916.648 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-05-06T23:55:16.711Z,1557186916.711 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-05-06T23:55:16.712Z,1557186916.712 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-05-06T23:55:16.727Z,1557186916.727 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-05-06T23:55:16.728Z,1557186916.728 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD34E0
2019-05-06T23:55:16.728Z,1557186916.728 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1238
2019-05-06T23:55:16.734Z,1557186916.734 [Supervisor](INFO): Main Thread ID is 1150
2019-05-06T23:55:16.734Z,1557186916.734 [Supervisor](DEBUG): Running supervisor.
2019-05-06T23:55:16.735Z,1557186916.735 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1239
2019-05-06T23:55:16.797Z,1557186916.797 [controlThread ThreadHandler](INFO): Handler Thread ID is 1240
2019-05-06T23:55:16.798Z,1557186916.798 [controlThread](DEBUG): Initializing ControlThread
2019-05-06T23:55:16.799Z,1557186916.799 [SBIT](INFO): Initialize SBIT Component.
2019-05-06T23:55:16.799Z,1557186916.799 [SBIT](IMPORTANT): git: 2019-05-01-6-g08bb213
2019-05-06T23:55:16.800Z,1557186916.800 [SBIT](INFO): git hash: 08bb2137fac00bb96d649b4144ef516361bafd54
2019-05-06T23:55:16.800Z,1557186916.800 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-05-06T23:55:16.802Z,1557186916.802 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-05-06T23:55:16.803Z,1557186916.803 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-05-06T23:55:16.803Z,1557186916.803 [IBIT](INFO): Initialize IBIT Component.
2019-05-06T23:55:16.804Z,1557186916.804 [CBIT](DEBUG): Initialize CBIT Component.
2019-05-06T23:55:16.805Z,1557186916.805 [logger ThreadHandler](INFO): Handler Thread ID is 1241
2019-05-06T23:55:16.817Z,1557186916.817 [CBIT](DEBUG): Initialized mux pins.
2019-05-06T23:55:16.817Z,1557186916.817 [CBIT](DEBUG): Initializing the watchdog timer.
2019-05-06T23:55:16.833Z,1557186916.833 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1242
2019-05-06T23:55:16.834Z,1557186916.834 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:55:16.836Z,1557186916.836 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1244
2019-05-06T23:55:16.838Z,1557186916.838 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:55:16.845Z,1557186916.845 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-05-06T23:55:16.845Z,1557186916.845 [CBIT](DEBUG): Initializing heartbeat.
2019-05-06T23:55:16.858Z,1557186916.858 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1245
2019-05-06T23:55:16.859Z,1557186916.859 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-05-06T23:55:16.862Z,1557186916.862 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:55:16.864Z,1557186916.864 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1246
2019-05-06T23:55:16.864Z,1557186916.864 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1247
2019-05-06T23:55:16.880Z,1557186916.880 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-05-06T23:55:16.880Z,1557186916.880 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-05-06T23:55:16.881Z,1557186916.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-05-06T23:55:16.881Z,1557186916.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-05-06T23:55:16.881Z,1557186916.881 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-05-06T23:55:16.881Z,1557186916.881 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-05-06T23:55:16.882Z,1557186916.882 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-05-06T23:55:16.883Z,1557186916.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-05-06T23:55:16.883Z,1557186916.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-05-06T23:55:16.883Z,1557186916.883 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-05-06T23:55:16.883Z,1557186916.883 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-05-06T23:55:16.917Z,1557186916.917 [CBIT](DEBUG): Deactivating GF circuits.
2019-05-06T23:55:16.917Z,1557186916.917 [CBIT](DEBUG): Deactivating emergency mode.
2019-05-06T23:55:16.953Z,1557186916.953 [CBIT](DEBUG): Backplane powered.
2019-05-06T23:55:16.957Z,1557186916.957 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-05-06T23:55:16.957Z,1557186916.957 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-05-06T23:55:16.958Z,1557186916.958 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-05-06T23:55:16.958Z,1557186916.958 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-05-06T23:55:16.959Z,1557186916.959 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-05-06T23:55:16.959Z,1557186916.959 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-05-06T23:55:16.960Z,1557186916.960 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-05-06T23:55:16.961Z,1557186916.961 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-05-06T23:55:16.974Z,1557186916.974 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-05-06T23:55:16.976Z,1557186916.976 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-05-06T23:55:16.985Z,1557186916.985 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-05-06T23:55:16.985Z,1557186916.985 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-05-06T23:55:16.986Z,1557186916.986 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-05-06T23:55:17.022Z,1557186917.022 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-06T23:55:17.053Z,1557186917.053 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2019-05-06T23:55:17.054Z,1557186917.054 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-05-06T23:55:17.129Z,1557186917.129 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-05-06T23:55:17.131Z,1557186917.131 [Default:A.Wait](DEBUG): Construct Wait.
2019-05-06T23:55:17.132Z,1557186917.132 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-05-06T23:55:17.179Z,1557186917.179 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-05-06T23:55:17.182Z,1557186917.182 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-05-06T23:55:17.203Z,1557186917.203 [Default:E.Execute](DEBUG): Construct Execute.
2019-05-06T23:55:17.207Z,1557186917.207 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2019-05-06T23:55:17.217Z,1557186917.217 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,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,
2019-05-06T23:55:17.238Z,1557186917.238 [ESPComponent](INFO): powering down ESP
2019-05-06T23:55:17.269Z,1557186917.269 [Radio_Surface](INFO): Powering up
2019-05-06T23:55:17.554Z,1557186917.554 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:55:17.554Z,1557186917.554 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:55:17.578Z,1557186917.578 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-05-06T23:55:17.649Z,1557186917.649 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-05-06T23:55:17.676Z,1557186917.676 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-05-06T23:55:17.681Z,1557186917.681 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-05-06T23:55:17.683Z,1557186917.683 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-05-06T23:55:17.689Z,1557186917.689 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-05-06T23:55:17.690Z,1557186917.690 [MassServo](DEBUG): Initializing EZServoServo.
2019-05-06T23:55:17.697Z,1557186917.697 [MassServo](DEBUG): Initializing MassServo.
2019-05-06T23:55:17.698Z,1557186917.698 [RudderServo](DEBUG): Initializing EZServoServo.
2019-05-06T23:55:17.705Z,1557186917.705 [RudderServo](DEBUG): Initializing RudderServo.
2019-05-06T23:55:17.706Z,1557186917.706 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-05-06T23:55:17.713Z,1557186917.713 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-05-06T23:55:24.630Z,1557186924.630 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-06T23:55:24.630Z,1557186924.630 [PNI_TCM] Data Fault, FailCount= 1
2019-05-06T23:55:24.631Z,1557186924.631 [PNI_TCM](ERROR): Data Fault
2019-05-06T23:55:24.793Z,1557186924.793 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-06T23:55:25.020Z,1557186925.020 [PNI_TCM](INFO): Powering down
2019-05-06T23:55:26.297Z,1557186926.297 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-06T23:55:26.297Z,1557186926.297 [PNI_TCM] No Fault, FailCount= 1
2019-05-06T23:55:27.111Z,1557186927.111 [CBIT](CRITICAL): Environmental Failure. Press:14.703206 PSI. Humidity:40%. Temp:26 C. ABORTING MISSION
2019-05-06T23:55:27.111Z,1557186927.111 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals
2019-05-06T23:55:27.421Z,1557186927.421 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:55:27.421Z,1557186927.421 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-06T23:55:27.421Z,1557186927.421 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:55:27.478Z,1557186927.478 [CommandLine](FAULT): Scheduling is paused
2019-05-06T23:55:27.479Z,1557186927.479 [CBIT](INFO): Critical error at 20190506T235527
2019-05-06T23:55:27.481Z,1557186927.481 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:55:27.949Z,1557186927.949 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:55:28.777Z,1557186928.777 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-05-06T23:55:28.777Z,1557186928.777 [PAR_Licor] Data Fault, FailCount= 1
2019-05-06T23:55:28.777Z,1557186928.777 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:28.860Z,1557186928.860 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-06T23:55:28.861Z,1557186928.861 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-06T23:55:28.861Z,1557186928.861 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:28.861Z,1557186928.861 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:28.861Z,1557186928.861 [PAR_Licor] No Fault, FailCount= 1
2019-05-06T23:55:29.350Z,1557186929.350 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004498
2019-05-06T23:55:29.981Z,1557186929.981 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-05-06T23:55:29.981Z,1557186929.981 [PAR_Licor] Data Fault, FailCount= 1
2019-05-06T23:55:29.981Z,1557186929.981 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:30.045Z,1557186930.045 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:30.045Z,1557186930.045 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:30.045Z,1557186930.045 [PAR_Licor] No Fault, FailCount= 1
2019-05-06T23:55:30.789Z,1557186930.789 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-05-06T23:55:30.789Z,1557186930.789 [PAR_Licor] Data Fault, FailCount= 2
2019-05-06T23:55:30.789Z,1557186930.789 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:30.873Z,1557186930.873 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:30.873Z,1557186930.873 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:30.873Z,1557186930.873 [PAR_Licor] No Fault, FailCount= 2
2019-05-06T23:55:30.889Z,1557186930.889 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:55:30.889Z,1557186930.889 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-06T23:55:30.889Z,1557186930.889 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:55:31.112Z,1557186931.112 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:55:31.293Z,1557186931.293 [CTD_Seabird](INFO): Powering down
2019-05-06T23:55:31.993Z,1557186931.993 [PAR_Licor](INFO): A/D timeout, 8 tries over 126 ms

2019-05-06T23:55:31.993Z,1557186931.993 [PAR_Licor] Data Fault, FailCount= 1
2019-05-06T23:55:31.993Z,1557186931.993 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:32.048Z,1557186932.048 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:32.057Z,1557186932.057 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:32.057Z,1557186932.057 [PAR_Licor] No Fault, FailCount= 1
2019-05-06T23:55:32.328Z,1557186932.328 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-06T23:55:32.328Z,1557186932.328 [CTD_Seabird] No Fault, FailCount= 1
2019-05-06T23:55:32.657Z,1557186932.657 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:55:32.659Z,1557186932.659 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:55:33.205Z,1557186933.205 [PAR_Licor](INFO): A/D timeout, 8 tries over 127 ms

2019-05-06T23:55:33.205Z,1557186933.205 [PAR_Licor] Data Fault, FailCount= 1
2019-05-06T23:55:33.205Z,1557186933.205 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:33.277Z,1557186933.277 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:33.277Z,1557186933.277 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:33.277Z,1557186933.277 [PAR_Licor] No Fault, FailCount= 1
2019-05-06T23:55:34.029Z,1557186934.029 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-05-06T23:55:34.029Z,1557186934.029 [PAR_Licor] Data Fault, FailCount= 2
2019-05-06T23:55:34.029Z,1557186934.029 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:34.069Z,1557186934.069 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-06T23:55:34.069Z,1557186934.069 [PNI_TCM] Data Fault, FailCount= 2
2019-05-06T23:55:34.069Z,1557186934.069 [PNI_TCM](ERROR): Data Fault
2019-05-06T23:55:34.100Z,1557186934.100 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:34.101Z,1557186934.101 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-05-06T23:55:34.101Z,1557186934.101 [PAR_Licor] No Fault, FailCount= 2
2019-05-06T23:55:34.101Z,1557186934.101 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-06T23:55:34.312Z,1557186934.312 [PNI_TCM](INFO): Powering down
2019-05-06T23:55:34.837Z,1557186934.837 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-05-06T23:55:34.837Z,1557186934.837 [PAR_Licor] Data Fault, FailCount= 3
2019-05-06T23:55:34.837Z,1557186934.837 [PAR_Licor](ERROR): Data Fault
2019-05-06T23:55:34.904Z,1557186934.904 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-05-06T23:55:34.905Z,1557186934.905 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-05-06T23:55:35.131Z,1557186935.131 [CBIT](INFO): Critical error at 20190506T235534
2019-05-06T23:55:35.553Z,1557186935.553 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-06T23:55:35.553Z,1557186935.553 [PNI_TCM] No Fault, FailCount= 2
2019-05-06T23:55:37.926Z,1557186937.926 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:55:37.926Z,1557186937.926 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-05-06T23:55:37.926Z,1557186937.926 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:55:37.976Z,1557186937.976 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:55:38.330Z,1557186938.330 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:55:39.138Z,1557186939.138 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:55:39.138Z,1557186939.138 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-06T23:55:39.138Z,1557186939.138 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:55:39.188Z,1557186939.188 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:55:39.188Z,1557186939.188 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:55:39.188Z,1557186939.188 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-05-06T23:55:39.661Z,1557186939.661 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:55:40.437Z,1557186940.437 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-06T23:55:40.437Z,1557186940.437 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-06T23:55:41.561Z,1557186941.561 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:55:41.561Z,1557186941.561 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:55:41.606Z,1557186941.606 [SBIT](IMPORTANT): Beginning Startup BIT
2019-05-06T23:55:41.610Z,1557186941.610 [CBIT](IMPORTANT): Beginning ground fault scan
2019-05-06T23:55:43.193Z,1557186943.193 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-06T23:55:43.193Z,1557186943.193 [PNI_TCM] Data Fault, FailCount= 3
2019-05-06T23:55:43.193Z,1557186943.193 [PNI_TCM](ERROR): Data Fault
2019-05-06T23:55:43.302Z,1557186943.302 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-06T23:55:43.601Z,1557186943.601 [PNI_TCM](INFO): Powering down
2019-05-06T23:55:44.942Z,1557186944.942 [NAL9602](INFO): Powering up NAL9602
2019-05-06T23:55:45.098Z,1557186945.098 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-06T23:55:45.098Z,1557186945.098 [PNI_TCM] No Fault, FailCount= 3
2019-05-06T23:55:45.709Z,1557186945.709 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:55:45.709Z,1557186945.709 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-06T23:55:45.709Z,1557186945.709 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:55:45.958Z,1557186945.958 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:55:46.113Z,1557186946.113 [CTD_Seabird](INFO): Powering down
2019-05-06T23:55:47.093Z,1557186947.093 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-06T23:55:47.093Z,1557186947.093 [CTD_Seabird] No Fault, FailCount= 2
2019-05-06T23:55:47.453Z,1557186947.453 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:55:47.455Z,1557186947.455 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:55:51.009Z,1557186951.009 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:55:51.009Z,1557186951.009 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-06T23:55:51.009Z,1557186951.009 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:55:51.131Z,1557186951.131 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:55:51.132Z,1557186951.132 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-06T23:55:51.533Z,1557186951.533 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:55:52.649Z,1557186952.649 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-06T23:55:52.650Z,1557186952.650 [PNI_TCM] Data Fault, FailCount= 4
2019-05-06T23:55:52.650Z,1557186952.650 [PNI_TCM](ERROR): Data Fault
2019-05-06T23:55:52.706Z,1557186952.706 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-06T23:55:52.710Z,1557186952.710 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.024029
CHAN A1 (24V): -0.132923
CHAN A2 (12V): -0.005137
CHAN A3 (5V): -0.002696
CHAN B0 (3.3V): -0.001310
CHAN B1 (3.15aV): -0.001600
CHAN B2 (3.15bV): -0.001149
CHAN B3 (GND): 0.000286
OPEN: 0.006556
Full Scale Calc: 4.765 mA, -1.589 mA
2019-05-06T23:55:53.049Z,1557186953.049 [PNI_TCM](INFO): Powering down
2019-05-06T23:55:54.326Z,1557186954.326 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-06T23:55:54.326Z,1557186954.326 [PNI_TCM] No Fault, FailCount= 4
2019-05-06T23:55:55.866Z,1557186955.866 [NAL9602](INFO): NAL9602 initialized
2019-05-06T23:55:56.685Z,1557186956.685 [NAL9602](DEBUG): Fix Requested
2019-05-06T23:56:00.505Z,1557186960.505 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:56:00.505Z,1557186960.505 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-06T23:56:00.506Z,1557186960.506 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:56:00.514Z,1557186960.514 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:56:00.514Z,1557186960.514 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-06T23:56:00.915Z,1557186960.915 [CTD_Seabird](INFO): Powering down
2019-05-06T23:56:01.598Z,1557186961.598 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:56:01.598Z,1557186961.598 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-05-06T23:56:01.598Z,1557186961.598 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:56:01.750Z,1557186961.750 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:56:02.002Z,1557186962.002 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:56:02.145Z,1557186962.145 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-06T23:56:02.145Z,1557186962.145 [PNI_TCM] Data Fault, FailCount= 5
2019-05-06T23:56:02.145Z,1557186962.145 [PNI_TCM](ERROR): Data Fault
2019-05-06T23:56:02.350Z,1557186962.350 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-06T23:56:02.350Z,1557186962.350 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-06T23:56:02.435Z,1557186962.435 [PNI_TCM](INFO): Powering down
2019-05-06T23:56:02.811Z,1557186962.811 [CBIT](INFO): Critical error at 20190506T235602
2019-05-06T23:56:02.926Z,1557186962.926 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:56:02.926Z,1557186962.926 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-05-06T23:56:05.242Z,1557186965.242 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:56:05.242Z,1557186965.242 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:56:25.529Z,1557186985.529 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:56:25.529Z,1557186985.529 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-05-06T23:56:25.529Z,1557186985.529 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:56:25.718Z,1557186985.718 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:56:25.933Z,1557186985.933 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:56:27.050Z,1557186987.050 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:56:27.050Z,1557186987.050 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-05-06T23:56:29.165Z,1557186989.165 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:56:29.165Z,1557186989.165 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:56:35.284Z,1557186995.284 [SBIT](IMPORTANT): SBIT PASSED
2019-05-06T23:56:35.381Z,1557186995.381 [CommandLine](IMPORTANT): got command configSet list
2019-05-06T23:56:35.382Z,1557186995.382 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-05-06T23:56:35.383Z,1557186995.383 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-05-06T23:56:35.383Z,1557186995.383 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-05-06T23:56:35.669Z,1557186995.669 [MissionManager](IMPORTANT): Started mission Startup
2019-05-06T23:56:35.669Z,1557186995.669 [Startup] Running Loop=1
2019-05-06T23:56:35.669Z,1557186995.669 [Startup](DEBUG): Aggregate::initialize Startup
2019-05-06T23:56:35.669Z,1557186995.669 [Startup:A.GoToSurface] Running Loop=1
2019-05-06T23:56:35.670Z,1557186995.670 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-06T23:56:35.670Z,1557186995.670 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-06T23:56:35.671Z,1557186995.671 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-06T23:56:35.671Z,1557186995.671 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-06T23:56:35.671Z,1557186995.671 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-06T23:56:35.672Z,1557186995.672 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-06T23:56:35.673Z,1557186995.673 [Startup:StartupSatComms] Running Loop=1
2019-05-06T23:56:35.673Z,1557186995.673 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-05-06T23:56:35.673Z,1557186995.673 [Startup:StartupSatComms:A] Running Loop=1
2019-05-06T23:56:36.073Z,1557186996.073 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-05-06T23:56:49.365Z,1557187009.365 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:56:49.365Z,1557187009.365 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-05-06T23:56:49.366Z,1557187009.366 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:56:49.404Z,1557187009.404 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:56:49.769Z,1557187009.769 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:56:50.615Z,1557187010.615 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:56:50.615Z,1557187010.615 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-05-06T23:56:53.001Z,1557187013.001 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:56:53.001Z,1557187013.001 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:57:12.475Z,1557187032.475 [CommandLine](IMPORTANT): got command configSet PAR_Licor.adcCal 0.000009 microampere_per_count
2019-05-06T23:57:13.201Z,1557187033.201 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:57:13.201Z,1557187033.201 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-05-06T23:57:13.201Z,1557187033.201 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:57:13.254Z,1557187033.254 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:57:13.605Z,1557187033.605 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:57:14.462Z,1557187034.462 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:57:14.462Z,1557187034.462 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-05-06T23:57:16.837Z,1557187036.837 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:57:16.837Z,1557187036.837 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:57:17.082Z,1557187037.082 [CommandLine](IMPORTANT): got command configSet PAR_Licor.adcCal 0.000009 microampere_per_count persist
2019-05-06T23:57:17.284Z,1557187037.284 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-06T23:57:17.284Z,1557187037.284 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-05-06T23:57:17.284Z,1557187037.284 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-06T23:57:17.295Z,1557187037.295 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-06T23:57:17.698Z,1557187037.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-06T23:57:17.698Z,1557187037.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-05-06T23:57:33.514Z,1557187053.514 [CommandLine](IMPORTANT): got command configSet PAR_Licor.darkCount 0.000000 count persist
2019-05-06T23:57:35.857Z,1557187055.857 [Startup:StartupSatComms:A](INFO): Timed out from 2019-05-06T23:56:35.7Z
2019-05-06T23:57:35.857Z,1557187055.857 [Startup:StartupSatComms:A] Stopped
2019-05-06T23:57:35.857Z,1557187055.857 [Startup:StartupSatComms:B] Running Loop=1
2019-05-06T23:57:36.261Z,1557187056.261 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-05-06T23:57:37.101Z,1557187057.101 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:57:37.102Z,1557187057.102 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-05-06T23:57:37.102Z,1557187057.102 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:57:37.167Z,1557187057.167 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:57:37.505Z,1557187057.505 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:57:38.370Z,1557187058.370 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:57:38.370Z,1557187058.370 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-05-06T23:57:40.737Z,1557187060.737 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:57:40.737Z,1557187060.737 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:57:43.042Z,1557187063.042 [CommandLine](IMPORTANT): got command get PAR_Licor.loadAtStartup
2019-05-06T23:57:43.042Z,1557187063.042 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup 1 bool
2019-05-06T23:57:43.682Z,1557187063.682 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20190501T210241/Courier0406.lzma
2019-05-06T23:57:44.487Z,1557187064.487 [DataOverHttps](INFO): Moved sent file to Logs/20190501T210241/Courier0406.lzma.bak
2019-05-06T23:57:44.487Z,1557187064.487 [DataOverHttps](INFO): SBD MOMSN=10945931
2019-05-06T23:58:00.937Z,1557187080.937 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:58:00.937Z,1557187080.937 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-05-06T23:58:00.937Z,1557187080.937 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:58:00.998Z,1557187080.998 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:58:01.341Z,1557187081.341 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:58:02.207Z,1557187082.207 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-05-06T23:58:02.208Z,1557187082.208 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-05-06T23:58:04.573Z,1557187084.573 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-05-06T23:58:04.573Z,1557187084.573 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-05-06T23:58:05.292Z,1557187085.292 [DataOverHttps](INFO): Sending 191 bytes from file Logs/20190506T235506/Courier0000.lzma
2019-05-06T23:58:06.098Z,1557187086.098 [DataOverHttps](INFO): Moved sent file to Logs/20190506T235506/Courier0000.lzma.bak
2019-05-06T23:58:06.098Z,1557187086.098 [DataOverHttps](INFO): SBD MOMSN=10945934
2019-05-06T23:58:10.059Z,1557187090.059 [CommandLine](IMPORTANT): got command configSet PAR_Licor.multiplier 190.300003 micromole_per_second_per_square_meter_per_microampere
2019-05-06T23:58:15.130Z,1557187095.130 [CommandLine](IMPORTANT): got command configSet PAR_Licor.multiplier 190.300003 micromole_per_second_per_square_meter_per_microampere persist
2019-05-06T23:58:21.182Z,1557187101.182 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-06T23:58:21.182Z,1557187101.182 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-06T23:58:21.754Z,1557187101.754 [DataOverHttps](INFO): Sending 408 bytes from file Logs/20190501T210241/Express0407.lzma
2019-05-06T23:58:22.559Z,1557187102.559 [DataOverHttps](INFO): Moved sent file to Logs/20190501T210241/Express0407.lzma.bak
2019-05-06T23:58:22.559Z,1557187102.559 [DataOverHttps](INFO): SBD MOMSN=10945940
2019-05-06T23:58:24.774Z,1557187104.774 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-05-06T23:58:24.774Z,1557187104.774 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-05-06T23:58:24.774Z,1557187104.774 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-05-06T23:58:24.887Z,1557187104.887 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:58:24.888Z,1557187104.888 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-05-06T23:58:25.178Z,1557187105.178 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-06T23:58:30.877Z,1557187110.877 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-06T23:58:30.878Z,1557187110.878 [CTD_Seabird] No Fault, FailCount= 3
2019-05-06T23:58:30.957Z,1557187110.957 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:58:30.959Z,1557187110.959 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:58:31.641Z,1557187111.641 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:58:31.641Z,1557187111.641 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-06T23:58:31.641Z,1557187111.641 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:58:31.725Z,1557187111.725 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:58:32.010Z,1557187112.010 [CommandLine](IMPORTANT): got command report touch PAR_Licor.adcCount
2019-05-06T23:58:32.161Z,1557187112.161 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:58:32.890Z,1557187112.890 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-06T23:58:32.891Z,1557187112.891 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-06T23:58:36.137Z,1557187116.137 [Startup:StartupSatComms:B](INFO): Timed out from 2019-05-06T23:57:35.9Z
2019-05-06T23:58:36.137Z,1557187116.137 [Startup:StartupSatComms:B] Stopped
2019-05-06T23:58:36.137Z,1557187116.137 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-05-06T23:58:36.137Z,1557187116.137 [Startup:StartupSatComms] Stopped
2019-05-06T23:58:36.137Z,1557187116.137 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-05-06T23:58:36.138Z,1557187116.138 [Startup](INFO): Completed Startup
2019-05-06T23:58:36.138Z,1557187116.138 [MissionManager](INFO): Startup is completed.
2019-05-06T23:58:36.139Z,1557187116.139 [MissionManager](INFO): Uninitializing Mission Startup
2019-05-06T23:58:36.139Z,1557187116.139 [Startup] Stopped
2019-05-06T23:58:36.139Z,1557187116.139 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-05-06T23:58:36.139Z,1557187116.139 [Startup:A.GoToSurface] Stopped
2019-05-06T23:58:36.139Z,1557187116.139 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-06T23:58:36.529Z,1557187116.529 [MissionManager](IMPORTANT): Started mission Default
2019-05-06T23:58:36.529Z,1557187116.529 [Default] Running Loop=1
2019-05-06T23:58:36.529Z,1557187116.529 [Default](DEBUG): Aggregate::initialize Default
2019-05-06T23:58:36.529Z,1557187116.529 [Default:B.GoToSurface] Running Loop=1
2019-05-06T23:58:36.529Z,1557187116.529 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-06T23:58:36.530Z,1557187116.530 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-06T23:58:36.530Z,1557187116.530 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-06T23:58:36.530Z,1557187116.530 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-06T23:58:36.531Z,1557187116.531 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-06T23:58:36.531Z,1557187116.531 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-06T23:58:36.531Z,1557187116.531 [Default:A.Wait] Running Loop=1
2019-05-06T23:58:36.531Z,1557187116.531 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-06T23:58:37.565Z,1557187117.565 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20190506T200148/Express0001.lzma
2019-05-06T23:58:38.370Z,1557187118.370 [DataOverHttps](INFO): Moved sent file to Logs/20190506T200148/Express0001.lzma.bak
2019-05-06T23:58:38.370Z,1557187118.370 [DataOverHttps](INFO): SBD MOMSN=10945961
2019-05-06T23:58:43.357Z,1557187123.357 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:58:43.357Z,1557187123.357 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-06T23:58:43.357Z,1557187123.357 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:58:43.402Z,1557187123.402 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:58:43.881Z,1557187123.881 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:58:44.009Z,1557187124.009 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:58:44.009Z,1557187124.009 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-06T23:58:44.009Z,1557187124.009 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:58:44.199Z,1557187124.199 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:58:44.413Z,1557187124.413 [CTD_Seabird](INFO): Powering down
2019-05-06T23:58:44.606Z,1557187124.606 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-06T23:58:44.606Z,1557187124.606 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-06T23:58:45.426Z,1557187125.426 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-06T23:58:45.426Z,1557187125.426 [CTD_Seabird] No Fault, FailCount= 1
2019-05-06T23:58:45.757Z,1557187125.757 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:58:45.759Z,1557187125.759 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:58:49.006Z,1557187129.006 [CommandLine](IMPORTANT): got command report touch PAR_Licor.adcCount
2019-05-06T23:58:49.851Z,1557187129.851 [Default:A.Wait](INFO): Done Waiting.
2019-05-06T23:58:49.851Z,1557187129.851 [Default:A.Wait] Stopped
2019-05-06T23:58:49.851Z,1557187129.851 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-06T23:58:50.254Z,1557187130.254 [Default:CheckIn] Running Loop=1
2019-05-06T23:58:50.254Z,1557187130.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-06T23:58:50.254Z,1557187130.254 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-06T23:58:50.658Z,1557187130.658 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-05-06T23:58:55.073Z,1557187135.073 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-06T23:58:55.073Z,1557187135.073 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-06T23:58:55.073Z,1557187135.073 [Aanderaa_O2](ERROR): Communications Fault
2019-05-06T23:58:55.265Z,1557187135.265 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-06T23:58:55.265Z,1557187135.265 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-06T23:58:55.597Z,1557187135.597 [Aanderaa_O2](INFO): Powering down
2019-05-06T23:58:58.817Z,1557187138.817 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:58:58.817Z,1557187138.817 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-06T23:58:58.817Z,1557187138.817 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:58:59.154Z,1557187139.154 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:58:59.221Z,1557187139.221 [CTD_Seabird](INFO): Powering down
2019-05-06T23:58:59.870Z,1557187139.870 [CommandLine](IMPORTANT): got command run 
2019-05-06T23:58:59.871Z,1557187139.871 [CommandLine](FAULT): Incomplete syntax. Try: help run
2019-05-06T23:59:00.362Z,1557187140.362 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-06T23:59:00.362Z,1557187140.362 [CTD_Seabird] No Fault, FailCount= 2
2019-05-06T23:59:00.565Z,1557187140.565 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:59:00.567Z,1557187140.567 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:59:02.723Z,1557187142.723 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2019-05-06T23:59:02.723Z,1557187142.723 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2019-05-06T23:59:02.941Z,1557187142.941 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2019-05-06T23:59:02.944Z,1557187142.944 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2019-05-06T23:59:02.948Z,1557187142.948 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2019-05-06T23:59:02.950Z,1557187142.950 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2019-05-06T23:59:02.954Z,1557187142.954 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2019-05-06T23:59:02.956Z,1557187142.956 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2019-05-06T23:59:02.959Z,1557187142.959 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2019-05-06T23:59:02.962Z,1557187142.962 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2019-05-06T23:59:02.966Z,1557187142.966 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2019-05-06T23:59:02.969Z,1557187142.969 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2019-05-06T23:59:02.972Z,1557187142.972 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2019-05-06T23:59:02.975Z,1557187142.975 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2019-05-06T23:59:02.977Z,1557187142.977 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2019-05-06T23:59:02.980Z,1557187142.980 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2019-05-06T23:59:02.984Z,1557187142.984 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool
2019-05-06T23:59:02.988Z,1557187142.988 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2019-05-06T23:59:03.004Z,1557187143.004 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2019-05-06T23:59:03.008Z,1557187143.008 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2019-05-06T23:59:03.012Z,1557187143.012 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2019-05-06T23:59:03.032Z,1557187143.032 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2019-05-06T23:59:03.036Z,1557187143.036 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2019-05-06T23:59:03.040Z,1557187143.040 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2019-05-06T23:59:03.069Z,1557187143.069 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2019-05-06T23:59:03.074Z,1557187143.074 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2019-05-06T23:59:03.078Z,1557187143.078 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2019-05-06T23:59:03.096Z,1557187143.096 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2019-05-06T23:59:03.104Z,1557187143.103 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2019-05-06T23:59:03.115Z,1557187143.115 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2019-05-06T23:59:03.118Z,1557187143.118 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2019-05-06T23:59:03.122Z,1557187143.122 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2019-05-06T23:59:03.145Z,1557187143.145 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2019-05-06T23:59:03.148Z,1557187143.148 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2019-05-06T23:59:03.205Z,1557187143.205 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2019-05-06T23:59:03.208Z,1557187143.208 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2019-05-06T23:59:03.221Z,1557187143.221 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2019-05-06T23:59:03.227Z,1557187143.227 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2019-05-06T23:59:03.231Z,1557187143.231 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2019-05-06T23:59:03.235Z,1557187143.235 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2019-05-06T23:59:03.250Z,1557187143.250 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2019-05-06T23:59:03.257Z,1557187143.257 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2019-05-06T23:59:03.265Z,1557187143.265 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2019-05-06T23:59:03.272Z,1557187143.272 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2019-05-06T23:59:03.275Z,1557187143.275 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2019-05-06T23:59:03.287Z,1557187143.287 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2019-05-06T23:59:03.299Z,1557187143.299 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2019-05-06T23:59:03.303Z,1557187143.303 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2019-05-06T23:59:03.319Z,1557187143.319 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2019-05-06T23:59:03.323Z,1557187143.323 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2019-05-06T23:59:03.334Z,1557187143.334 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2019-05-06T23:59:03.343Z,1557187143.343 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2019-05-06T23:59:03.355Z,1557187143.355 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2019-05-06T23:59:03.398Z,1557187143.398 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-06T23:59:03.428Z,1557187143.428 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2019-05-06T23:59:03.523Z,1557187143.523 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-06T23:59:03.546Z,1557187143.546 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-06T23:59:03.606Z,1557187143.606 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2019-05-06T23:59:03.673Z,1557187143.673 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="SampleISUS"><Description>
        Set either this or PeakDetectNO3Active to True in order to sample
        Nitrate. EnabledISUS must also be true.
    </Description><False/></DefineArg>

    <DefineArg Name="SampleISUSmaxPitch"><Description>
        Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>60.0</Value></DefineArg>

    <DefineArg Name="SampleISUSminPitch"><Description>
        Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>-60.0</Value></DefineArg>

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="TimeWindowPeakReport"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>NaN</Value></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

<!--debug
    </Description><True/></DefineArg>
-->

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="OffPeakFractionHorizontal"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor.
    </Description><False/></DefineArg>
    
    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledTurbulence_NPS"><Description>
        Automatically set to true if the NPS Turbulence sensor is enabled. Set
        to false to disable reading from the NPS Turbulence sensor.
    </Description><Science:Turbulence_NPS.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set
        to false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>    

    <DefineArg Name="EnabledISUS"><Description>
        Automatically set to True if ISUS is enabled. Set to false to disable
        ISUS.
    </Description><Science:ISUS.loadAtStartup/></DefineArg>

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="PeakChlShallowBound"><Description>
        Shallow depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>10</Value></DefineArg>
-->

    <DefineArg Name="PeakChlDeepBound"><Description>
        Deep depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>20</Value></DefineArg>
-->

    <DefineArg Name="PeakShallowBound"><Description>
        Shallow depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakDeepBound"><Description>
        Deep depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOilLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>
    
    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>    
    
    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData>
        <While>
            <Arg Name="EnabledISUS"/>
            <And><Universal:platform_pitch_angle/><Lt><Arg Name="SampleISUSmaxPitch"/></Lt><And><Universal:platform_pitch_angle/><Gt><Arg Name="SampleISUSminPitch"/></Gt><And><Arg Name="SampleISUS"/><Or><Arg Name="PeakDetectNO3Active"/></Or></And></And></And>
        </While>
        <Universal:mole_concentration_of_nitrate_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledTurbulence_NPS"/>
        </While>
        <Science:Turbulence_NPS.MicroCond/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

<!--debug-->

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2019-05-06T23:59:03.680Z,1557187143.680 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2019-05-06T23:59:03.997Z,1557187143.997 [Default] Stopped
2019-05-06T23:59:03.997Z,1557187143.997 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-06T23:59:03.997Z,1557187143.997 [Default:B.GoToSurface] Stopped
2019-05-06T23:59:03.997Z,1557187143.997 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-06T23:59:03.997Z,1557187143.997 [Default:CheckIn] Stopped
2019-05-06T23:59:03.997Z,1557187143.997 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-06T23:59:03.997Z,1557187143.997 [Default:CheckIn:Read_GPS] Stopped
2019-05-06T23:59:03.997Z,1557187143.997 [MissionManager](IMPORTANT): Started mission Science
2019-05-06T23:59:03.998Z,1557187143.998 [Science] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science](DEBUG): Aggregate::initialize Science
2019-05-06T23:59:03.998Z,1557187143.998 [Science:A] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:B] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:C] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:D] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:E] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:F] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:G] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:H] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:Read_Oil] Running Loop=1
2019-05-06T23:59:03.998Z,1557187143.998 [Science:PeakDetectChl] Running Loop=1
2019-05-06T23:59:03.999Z,1557187143.999 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2019-05-06T23:59:03.999Z,1557187143.999 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2019-05-06T23:59:03.999Z,1557187143.999 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-06T23:59:04.001Z,1557187144.001 [Science:HighestChlPeakReport] Running Loop=1
2019-05-06T23:59:04.001Z,1557187144.001 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2019-05-06T23:59:04.001Z,1557187144.001 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2019-05-06T23:59:04.001Z,1557187144.001 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2019-05-06T23:59:04.002Z,1557187144.002 [Science:PeakDetectNO3] Running Loop=1
2019-05-06T23:59:04.002Z,1557187144.002 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2019-05-06T23:59:04.002Z,1557187144.002 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2019-05-06T23:59:04.002Z,1557187144.002 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-06T23:59:04.004Z,1557187144.004 [Science:PeakDetectOil] Running Loop=1
2019-05-06T23:59:04.004Z,1557187144.004 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2019-05-06T23:59:04.004Z,1557187144.004 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2019-05-06T23:59:04.004Z,1557187144.004 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-06T23:59:04.038Z,1557187144.038 [Science:PeakDetectFDOM] Running Loop=1
2019-05-06T23:59:04.038Z,1557187144.038 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2019-05-06T23:59:04.038Z,1557187144.038 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2019-05-06T23:59:04.038Z,1557187144.038 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2019-05-06T23:59:04.041Z,1557187144.041 [Science:PeakDetectFDOM] Stopped
2019-05-06T23:59:04.041Z,1557187144.041 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2019-05-06T23:59:04.041Z,1557187144.041 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2019-05-06T23:59:04.041Z,1557187144.042 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectOil] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectNO3] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-06T23:59:04.042Z,1557187144.042 [Science:HighestChlPeakReport] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2019-05-06T23:59:04.042Z,1557187144.042 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2019-05-06T23:59:04.042Z,1557187144.042 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2019-05-06T23:59:04.043Z,1557187144.043 [Science:PeakDetectChl] Stopped
2019-05-06T23:59:04.043Z,1557187144.043 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2019-05-06T23:59:04.043Z,1557187144.043 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2019-05-06T23:59:04.043Z,1557187144.043 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2019-05-06T23:59:04.043Z,1557187144.043 [Science:Read_Oil] Stopped
2019-05-06T23:59:04.043Z,1557187144.043 [Science:H] Stopped
2019-05-06T23:59:04.043Z,1557187144.043 [Science:G] Running Loop=1
2019-05-06T23:59:04.044Z,1557187144.044 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2019-05-06T23:59:04.044Z,1557187144.044 [Science:F] Stopped
2019-05-06T23:59:04.044Z,1557187144.044 [Science:E] Stopped
2019-05-06T23:59:04.044Z,1557187144.044 [Science:D] Running Loop=1
2019-05-06T23:59:04.057Z,1557187144.057 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2019-05-06T23:59:04.058Z,1557187144.058 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2019-05-06T23:59:04.058Z,1557187144.058 [Science:C] Running Loop=1
2019-05-06T23:59:04.059Z,1557187144.059 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2019-05-06T23:59:04.059Z,1557187144.059 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2019-05-06T23:59:04.059Z,1557187144.059 [Science:B] Stopped
2019-05-06T23:59:04.059Z,1557187144.059 [Science:A] Running Loop=1
2019-05-06T23:59:04.060Z,1557187144.060 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2019-05-06T23:59:04.222Z,1557187144.222 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-06T23:59:04.222Z,1557187144.222 [WetLabsBB2FL](INFO): Powering up
2019-05-06T23:59:09.517Z,1557187149.517 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-05-06T23:59:09.517Z,1557187149.517 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-06T23:59:09.676Z,1557187149.676 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:10.017Z,1557187150.017 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:59:10.866Z,1557187150.866 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-06T23:59:10.867Z,1557187150.867 [WetLabsBB2FL] No Fault, FailCount= 1
2019-05-06T23:59:11.238Z,1557187151.238 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-06T23:59:11.239Z,1557187151.239 [WetLabsBB2FL](INFO): Powering up
2019-05-06T23:59:12.219Z,1557187152.219 [CommandLine](IMPORTANT): got command report touch PAR_Licor.adcCount
2019-05-06T23:59:13.617Z,1557187153.617 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-06T23:59:13.617Z,1557187153.617 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-06T23:59:13.617Z,1557187153.617 [CTD_Seabird](ERROR): Communications Fault
2019-05-06T23:59:13.702Z,1557187153.702 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-06T23:59:13.702Z,1557187153.702 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-06T23:59:14.021Z,1557187154.021 [CTD_Seabird](INFO): Powering down
2019-05-06T23:59:14.965Z,1557187154.965 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-06T23:59:14.967Z,1557187154.967 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-06T23:59:16.529Z,1557187156.529 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-05-06T23:59:16.529Z,1557187156.529 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-06T23:59:16.531Z,1557187156.531 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:17.029Z,1557187157.029 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:59:18.204Z,1557187158.204 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-06T23:59:18.205Z,1557187158.205 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-05-06T23:59:18.205Z,1557187158.205 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-06T23:59:18.216Z,1557187158.216 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-06T23:59:18.216Z,1557187158.216 [WetLabsBB2FL] No Fault, FailCount= 2
2019-05-06T23:59:18.216Z,1557187158.216 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-06T23:59:18.242Z,1557187158.242 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-06T23:59:18.242Z,1557187158.242 [WetLabsBB2FL](INFO): Powering up
2019-05-06T23:59:18.567Z,1557187158.567 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-06T23:59:18.567Z,1557187158.567 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-05-06T23:59:22.958Z,1557187162.958 [CommandLine](IMPORTANT): got command stop
2019-05-06T23:59:22.958Z,1557187162.958 [CommandLine](IMPORTANT): Scheduling is paused
2019-05-06T23:59:22.958Z,1557187162.958 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-05-06T23:59:23.018Z,1557187163.018 [MissionManager](INFO): MissionManager is completed.
2019-05-06T23:59:23.018Z,1557187163.018 [MissionManager](INFO): Uninitializing Mission Science
2019-05-06T23:59:23.018Z,1557187163.018 [Science] Stopped
2019-05-06T23:59:23.018Z,1557187163.018 [Science](DEBUG): Aggregate::uninitialize Science
2019-05-06T23:59:23.018Z,1557187163.018 [Science:A] Stopped
2019-05-06T23:59:23.018Z,1557187163.018 [Science:C] Stopped
2019-05-06T23:59:23.018Z,1557187163.018 [Science:D] Stopped
2019-05-06T23:59:23.019Z,1557187163.019 [Science:G] Stopped
2019-05-06T23:59:23.401Z,1557187163.401 [MissionManager](IMPORTANT): Started mission Default
2019-05-06T23:59:23.401Z,1557187163.401 [Default] Running Loop=1
2019-05-06T23:59:23.401Z,1557187163.401 [Default](DEBUG): Aggregate::initialize Default
2019-05-06T23:59:23.401Z,1557187163.401 [Default:B.GoToSurface] Running Loop=1
2019-05-06T23:59:23.401Z,1557187163.401 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-05-06T23:59:23.401Z,1557187163.401 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-05-06T23:59:23.402Z,1557187163.402 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-05-06T23:59:23.402Z,1557187163.402 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-05-06T23:59:23.402Z,1557187163.402 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-05-06T23:59:23.403Z,1557187163.403 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-05-06T23:59:23.403Z,1557187163.403 [Default:A.Wait] Running Loop=1
2019-05-06T23:59:23.403Z,1557187163.403 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-05-06T23:59:23.537Z,1557187163.537 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-05-06T23:59:23.537Z,1557187163.537 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-06T23:59:23.810Z,1557187163.810 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:24.037Z,1557187164.037 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:59:25.007Z,1557187165.007 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-06T23:59:25.007Z,1557187165.007 [WetLabsBB2FL] No Fault, FailCount= 3
2019-05-06T23:59:25.250Z,1557187165.250 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-06T23:59:25.250Z,1557187165.250 [WetLabsBB2FL](INFO): Powering up
2019-05-06T23:59:30.541Z,1557187170.541 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-05-06T23:59:30.541Z,1557187170.541 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-06T23:59:30.663Z,1557187170.663 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:31.046Z,1557187171.046 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:59:31.883Z,1557187171.883 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-06T23:59:31.883Z,1557187171.883 [WetLabsBB2FL] No Fault, FailCount= 4
2019-05-06T23:59:32.258Z,1557187172.258 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-06T23:59:32.258Z,1557187172.258 [WetLabsBB2FL](INFO): Powering up
2019-05-06T23:59:35.627Z,1557187175.627 [CommandLine](IMPORTANT): got command show variable PAR
2019-05-06T23:59:35.730Z,1557187175.730 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none)
2019-05-06T23:59:35.730Z,1557187175.730 [CommandLine](IMPORTANT): PAR_Licor.ad (none)
2019-05-06T23:59:35.731Z,1557187175.731 [CommandLine](IMPORTANT): PAR_Licor.adRes (bit)
2019-05-06T23:59:35.731Z,1557187175.731 [CommandLine](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2019-05-06T23:59:35.732Z,1557187175.732 [CommandLine](IMPORTANT): PAR_Licor.adVref (volt)
2019-05-06T23:59:35.748Z,1557187175.748 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2019-05-06T23:59:35.748Z,1557187175.748 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware (bool)
2019-05-06T23:59:35.749Z,1557187175.749 [CommandLine](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2019-05-06T23:59:35.749Z,1557187175.749 [CommandLine](IMPORTANT): PAR_Licor.darkCount (count)
2019-05-06T23:59:35.749Z,1557187175.749 [CommandLine](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2019-05-06T23:59:35.750Z,1557187175.750 [CommandLine](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2019-05-06T23:59:35.750Z,1557187175.750 [CommandLine](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2019-05-06T23:59:35.750Z,1557187175.750 [CommandLine](IMPORTANT): PAR_Licor.minValidPitch (degree)
2019-05-06T23:59:35.751Z,1557187175.751 [CommandLine](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2019-05-06T23:59:35.751Z,1557187175.751 [CommandLine](IMPORTANT): PAR_Licor.serial (none)
2019-05-06T23:59:35.765Z,1557187175.765 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2019-05-06T23:59:35.766Z,1557187175.766 [CommandLine](IMPORTANT): PAR_Licor.adcCount (count)
2019-05-06T23:59:36.714Z,1557187176.714 [Default:A.Wait](INFO): Done Waiting.
2019-05-06T23:59:36.714Z,1557187176.714 [Default:A.Wait] Stopped
2019-05-06T23:59:36.714Z,1557187176.714 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-05-06T23:59:37.119Z,1557187177.119 [Default:CheckIn] Running Loop=1
2019-05-06T23:59:37.119Z,1557187177.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-05-06T23:59:37.119Z,1557187177.119 [Default:CheckIn:Read_GPS] Running Loop=1
2019-05-06T23:59:37.549Z,1557187177.549 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-05-06T23:59:37.549Z,1557187177.549 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-06T23:59:37.953Z,1557187177.953 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:37.953Z,1557187177.953 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-05-06T23:59:38.045Z,1557187178.045 [WetLabsBB2FL](INFO): Powering down
2019-05-06T23:59:47.471Z,1557187187.471 [CommandLine](IMPORTANT): got command report touch PAR_Licor.adcCount
2019-05-07T00:00:59.106Z,1557187259.106 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-05-07T00:00:59.925Z,1557187259.925 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:02.349Z,1557187262.349 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:02.387Z,1557187262.387 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T00:01:02.387Z,1557187262.387 [PNI_TCM] No Fault, FailCount= 5
2019-05-07T00:01:05.605Z,1557187265.605 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:08.410Z,1557187268.410 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:10.030Z,1557187270.030 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T00:01:10.030Z,1557187270.030 [PNI_TCM] Data Fault, FailCount= 1
2019-05-07T00:01:10.030Z,1557187270.030 [PNI_TCM](ERROR): Data Fault
2019-05-07T00:01:10.047Z,1557187270.047 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T00:01:10.438Z,1557187270.438 [PNI_TCM](INFO): Powering down
2019-05-07T00:01:11.641Z,1557187271.641 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:11.696Z,1557187271.696 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T00:01:11.696Z,1557187271.696 [PNI_TCM] No Fault, FailCount= 1
2019-05-07T00:01:14.469Z,1557187274.469 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:17.675Z,1557187277.675 [CommandLine](IMPORTANT): got command get PAR_Licor.simulateHardware
2019-05-07T00:01:17.675Z,1557187277.675 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware 0 bool
2019-05-07T00:01:17.705Z,1557187277.705 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:19.324Z,1557187279.324 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T00:01:19.324Z,1557187279.324 [PNI_TCM] Data Fault, FailCount= 2
2019-05-07T00:01:19.324Z,1557187279.324 [PNI_TCM](ERROR): Data Fault
2019-05-07T00:01:19.431Z,1557187279.431 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-05-07T00:01:19.431Z,1557187279.431 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-05-07T00:01:19.431Z,1557187279.431 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-05-07T00:01:19.455Z,1557187279.455 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-05-07T00:01:19.455Z,1557187279.455 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T00:01:19.725Z,1557187279.725 [PNI_TCM](INFO): Powering down
2019-05-07T00:01:19.849Z,1557187279.849 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-05-07T00:01:19.850Z,1557187279.850 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-05-07T00:01:20.537Z,1557187280.537 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:20.974Z,1557187280.974 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T00:01:20.974Z,1557187280.974 [PNI_TCM] No Fault, FailCount= 2
2019-05-07T00:01:23.765Z,1557187283.765 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:25.399Z,1557187285.399 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T00:01:25.399Z,1557187285.399 [Aanderaa_O2] No Fault, FailCount= 3
2019-05-07T00:01:26.593Z,1557187286.593 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:28.618Z,1557187288.618 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T00:01:28.618Z,1557187288.618 [PNI_TCM] Data Fault, FailCount= 3
2019-05-07T00:01:28.618Z,1557187288.618 [PNI_TCM](ERROR): Data Fault
2019-05-07T00:01:28.666Z,1557187288.666 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T00:01:29.016Z,1557187289.016 [PNI_TCM](INFO): Powering down
2019-05-07T00:01:29.421Z,1557187289.421 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:30.258Z,1557187290.258 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T00:01:30.259Z,1557187290.259 [PNI_TCM] No Fault, FailCount= 3
2019-05-07T00:01:31.910Z,1557187291.910 [CommandLine](IMPORTANT): got command show variable PAR
2019-05-07T00:01:31.939Z,1557187291.939 [CommandLine](IMPORTANT): PAR_Licor.loadControl (none)
2019-05-07T00:01:31.939Z,1557187291.939 [CommandLine](IMPORTANT): PAR_Licor.ad (none)
2019-05-07T00:01:31.939Z,1557187291.939 [CommandLine](IMPORTANT): PAR_Licor.adRes (bit)
2019-05-07T00:01:31.940Z,1557187291.940 [CommandLine](IMPORTANT): PAR_Licor.adTimeout (millisecond)
2019-05-07T00:01:31.940Z,1557187291.940 [CommandLine](IMPORTANT): PAR_Licor.adVref (volt)
2019-05-07T00:01:31.964Z,1557187291.964 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup (bool)
2019-05-07T00:01:31.964Z,1557187291.964 [CommandLine](IMPORTANT): PAR_Licor.simulateHardware (bool)
2019-05-07T00:01:31.965Z,1557187291.965 [CommandLine](IMPORTANT): PAR_Licor.adcCal (microampere_per_count)
2019-05-07T00:01:31.965Z,1557187291.965 [CommandLine](IMPORTANT): PAR_Licor.darkCount (count)
2019-05-07T00:01:31.965Z,1557187291.965 [CommandLine](IMPORTANT): PAR_Licor.maxBound (micromole_per_second_per_square_meter)
2019-05-07T00:01:31.966Z,1557187291.966 [CommandLine](IMPORTANT): PAR_Licor.maxValidPitch (degree)
2019-05-07T00:01:31.966Z,1557187291.966 [CommandLine](IMPORTANT): PAR_Licor.minBound (micromole_per_second_per_square_meter)
2019-05-07T00:01:31.966Z,1557187291.966 [CommandLine](IMPORTANT): PAR_Licor.minValidPitch (degree)
2019-05-07T00:01:31.967Z,1557187291.967 [CommandLine](IMPORTANT): PAR_Licor.multiplier (micromole_per_second_per_square_meter_per_microampere)
2019-05-07T00:01:31.967Z,1557187291.967 [CommandLine](IMPORTANT): PAR_Licor.serial (none)
2019-05-07T00:01:32.029Z,1557187292.029 [CommandLine](IMPORTANT): PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water (micromole_per_second_per_square_meter)
2019-05-07T00:01:32.030Z,1557187292.030 [CommandLine](IMPORTANT): PAR_Licor.adcCount (count)
2019-05-07T00:01:32.649Z,1557187292.649 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:35.481Z,1557187295.481 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:35.865Z,1557187295.865 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T00:01:35.865Z,1557187295.865 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-05-07T00:01:35.865Z,1557187295.865 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T00:01:35.918Z,1557187295.918 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T00:01:36.389Z,1557187296.389 [Aanderaa_O2](INFO): Powering down
2019-05-07T00:01:37.118Z,1557187297.118 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T00:01:37.118Z,1557187297.118 [Aanderaa_O2] No Fault, FailCount= 1
2019-05-07T00:01:37.922Z,1557187297.922 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T00:01:37.922Z,1557187297.922 [PNI_TCM] Data Fault, FailCount= 4
2019-05-07T00:01:37.922Z,1557187297.922 [PNI_TCM](ERROR): Data Fault
2019-05-07T00:01:37.967Z,1557187297.967 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T00:01:38.312Z,1557187298.312 [PNI_TCM](INFO): Powering down
2019-05-07T00:01:38.709Z,1557187298.709 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:39.550Z,1557187299.550 [CBIT](INFO): Clearing failed state for component PNI_TCM
2019-05-07T00:01:39.550Z,1557187299.550 [PNI_TCM] No Fault, FailCount= 4
2019-05-07T00:01:39.802Z,1557187299.802 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:01:39.802Z,1557187299.802 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:01:41.541Z,1557187301.541 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:43.994Z,1557187303.994 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T00:01:43.994Z,1557187303.994 [CTD_Seabird] No Fault, FailCount= 3
2019-05-07T00:01:44.101Z,1557187304.101 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T00:01:44.101Z,1557187304.101 [CTD_Seabird] Communications Fault, FailCount= 1
2019-05-07T00:01:44.101Z,1557187304.101 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T00:01:44.406Z,1557187304.406 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T00:01:44.505Z,1557187304.505 [CTD_Seabird](INFO): Powering down
2019-05-07T00:01:44.769Z,1557187304.769 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:45.667Z,1557187305.667 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T00:01:45.667Z,1557187305.667 [CTD_Seabird] No Fault, FailCount= 1
2019-05-07T00:01:45.849Z,1557187305.849 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T00:01:45.851Z,1557187305.851 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T00:01:47.202Z,1557187307.202 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2019-05-07T00:01:47.202Z,1557187307.202 [PNI_TCM] Data Fault, FailCount= 5
2019-05-07T00:01:47.202Z,1557187307.202 [PNI_TCM](ERROR): Data Fault
2019-05-07T00:01:47.238Z,1557187307.238 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:01:47.238Z,1557187307.238 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:01:47.243Z,1557187307.243 [CBIT](ERROR): Data Fault in component: PNI_TCM
2019-05-07T00:01:47.244Z,1557187307.244 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2019-05-07T00:01:47.581Z,1557187307.581 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T00:01:47.581Z,1557187307.581 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-05-07T00:01:47.581Z,1557187307.581 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T00:01:47.597Z,1557187307.597 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:47.629Z,1557187307.629 [PNI_TCM](INFO): Powering down
2019-05-07T00:01:47.735Z,1557187307.735 [CommandLine](FAULT): Scheduling is paused
2019-05-07T00:01:47.736Z,1557187307.736 [CBIT](INFO): Critical error at 20190507T000147
2019-05-07T00:01:47.738Z,1557187307.738 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T00:01:48.105Z,1557187308.105 [Aanderaa_O2](INFO): Powering down
2019-05-07T00:01:48.830Z,1557187308.830 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-05-07T00:01:48.830Z,1557187308.830 [Aanderaa_O2] No Fault, FailCount= 2
2019-05-07T00:01:49.922Z,1557187309.922 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:01:49.923Z,1557187309.923 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:01:50.425Z,1557187310.425 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:51.906Z,1557187311.906 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:01:51.907Z,1557187311.907 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:01:52.449Z,1557187312.449 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:53.306Z,1557187313.306 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:01:53.306Z,1557187313.306 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:01:55.681Z,1557187315.681 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:58.509Z,1557187318.509 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:01:58.901Z,1557187318.901 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T00:01:58.901Z,1557187318.901 [CTD_Seabird] Communications Fault, FailCount= 2
2019-05-07T00:01:58.901Z,1557187318.901 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T00:01:59.022Z,1557187319.022 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T00:01:59.297Z,1557187319.297 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-05-07T00:01:59.297Z,1557187319.297 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-05-07T00:01:59.297Z,1557187319.297 [Aanderaa_O2](ERROR): Communications Fault
2019-05-07T00:01:59.305Z,1557187319.305 [CTD_Seabird](INFO): Powering down
2019-05-07T00:01:59.458Z,1557187319.458 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-05-07T00:01:59.459Z,1557187319.459 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-05-07T00:01:59.821Z,1557187319.821 [Aanderaa_O2](INFO): Powering down
2019-05-07T00:02:00.566Z,1557187320.566 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-05-07T00:02:00.566Z,1557187320.566 [CTD_Seabird] No Fault, FailCount= 2
2019-05-07T00:02:00.649Z,1557187320.649 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-05-07T00:02:00.651Z,1557187320.651 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-05-07T00:02:01.741Z,1557187321.741 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:04.569Z,1557187324.569 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:07.805Z,1557187327.805 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:07.835Z,1557187327.835 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-07T00:02:07.835Z,1557187327.835 [WetLabsBB2FL] No Fault, FailCount= 5
2019-05-07T00:02:07.954Z,1557187327.954 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-07T00:02:07.954Z,1557187327.954 [WetLabsBB2FL](INFO): Powering up
2019-05-07T00:02:10.629Z,1557187330.629 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:13.249Z,1557187333.249 [WetLabsBB2FL] Communications Fault, FailCount= 1
2019-05-07T00:02:13.249Z,1557187333.249 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-07T00:02:13.453Z,1557187333.453 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:13.534Z,1557187333.534 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:13.725Z,1557187333.725 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-05-07T00:02:13.725Z,1557187333.725 [CTD_Seabird] Communications Fault, FailCount= 3
2019-05-07T00:02:13.725Z,1557187333.725 [CTD_Seabird](ERROR): Communications Fault
2019-05-07T00:02:13.749Z,1557187333.749 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:13.902Z,1557187333.902 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-05-07T00:02:13.902Z,1557187333.902 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-05-07T00:02:14.129Z,1557187334.129 [CTD_Seabird](INFO): Powering down
2019-05-07T00:02:14.695Z,1557187334.695 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-07T00:02:14.695Z,1557187334.695 [WetLabsBB2FL] No Fault, FailCount= 1
2019-05-07T00:02:14.962Z,1557187334.962 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-07T00:02:14.962Z,1557187334.962 [WetLabsBB2FL](INFO): Powering up
2019-05-07T00:02:16.689Z,1557187336.689 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:19.513Z,1557187339.513 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:20.253Z,1557187340.253 [WetLabsBB2FL] Communications Fault, FailCount= 2
2019-05-07T00:02:20.253Z,1557187340.253 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-07T00:02:20.354Z,1557187340.354 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:20.749Z,1557187340.749 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:21.562Z,1557187341.562 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-07T00:02:21.562Z,1557187341.562 [WetLabsBB2FL] No Fault, FailCount= 2
2019-05-07T00:02:21.962Z,1557187341.962 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-07T00:02:21.962Z,1557187341.962 [WetLabsBB2FL](INFO): Powering up
2019-05-07T00:02:22.749Z,1557187342.749 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:25.577Z,1557187345.577 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:27.253Z,1557187347.253 [WetLabsBB2FL] Communications Fault, FailCount= 3
2019-05-07T00:02:27.253Z,1557187347.253 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-07T00:02:27.614Z,1557187347.614 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:27.753Z,1557187347.753 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:28.809Z,1557187348.809 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:28.844Z,1557187348.844 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-07T00:02:28.844Z,1557187348.844 [WetLabsBB2FL] No Fault, FailCount= 3
2019-05-07T00:02:28.966Z,1557187348.966 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-07T00:02:28.966Z,1557187348.966 [WetLabsBB2FL](INFO): Powering up
2019-05-07T00:02:31.637Z,1557187351.637 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:34.257Z,1557187354.257 [WetLabsBB2FL] Communications Fault, FailCount= 4
2019-05-07T00:02:34.257Z,1557187354.257 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-07T00:02:34.479Z,1557187354.479 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:34.757Z,1557187354.757 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:34.873Z,1557187354.873 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:35.714Z,1557187355.714 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2019-05-07T00:02:35.714Z,1557187355.714 [WetLabsBB2FL] No Fault, FailCount= 4
2019-05-07T00:02:35.970Z,1557187355.970 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=2
2019-05-07T00:02:35.970Z,1557187355.970 [WetLabsBB2FL](INFO): Powering up
2019-05-07T00:02:37.697Z,1557187357.697 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:40.451Z,1557187360.451 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:02:40.451Z,1557187360.451 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:02:40.521Z,1557187360.521 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:41.261Z,1557187361.261 [WetLabsBB2FL] Communications Fault, FailCount= 5
2019-05-07T00:02:41.261Z,1557187361.261 [WetLabsBB2FL](ERROR): Communications Fault
2019-05-07T00:02:41.386Z,1557187361.386 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:41.386Z,1557187361.386 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2019-05-07T00:02:41.757Z,1557187361.757 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:43.351Z,1557187363.351 [CommandLine](IMPORTANT): got command get PAR_Licor.adcCount
2019-05-07T00:02:43.352Z,1557187363.352 [CommandLine](IMPORTANT): PAR_Licor.adcCount 0 count
2019-05-07T00:02:43.753Z,1557187363.753 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:46.581Z,1557187366.581 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:49.817Z,1557187369.817 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:52.645Z,1557187372.645 [NAL9602](DEBUG): Fix Requested
2019-05-07T00:02:52.672Z,1557187372.672 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-05-07T00:02:52.675Z,1557187372.675 [BPC1](INFO): Received data from all battery sticks.
2019-05-07T00:02:52.865Z,1557187372.865 [CommandLine](IMPORTANT): got command quit
2019-05-07T00:02:53.869Z,1557187373.869 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:53.869Z,1557187373.869 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:53.893Z,1557187373.893 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-05-07T00:02:53.893Z,1557187373.893 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:53.894Z,1557187373.894 [CommandLine](INFO): Join timeout helper Thread ID is 1319
2019-05-07T00:02:53.909Z,1557187373.909 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-05-07T00:02:53.909Z,1557187373.909 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:53.910Z,1557187373.910 [NavChartDb](INFO): Join timeout helper Thread ID is 1320
2019-05-07T00:02:54.005Z,1557187374.005 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:54.005Z,1557187374.005 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.017Z,1557187374.017 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-05-07T00:02:54.017Z,1557187374.017 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.017Z,1557187374.017 [Radio_Surface](INFO): Join timeout helper Thread ID is 1321
2019-05-07T00:02:54.265Z,1557187374.265 [Radio_Surface](INFO): Powering down
2019-05-07T00:02:54.266Z,1557187374.266 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:54.266Z,1557187374.266 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.277Z,1557187374.277 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-05-07T00:02:54.277Z,1557187374.277 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.277Z,1557187374.277 [DataOverHttps](INFO): Join timeout helper Thread ID is 1322
2019-05-07T00:02:54.397Z,1557187374.397 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:54.397Z,1557187374.397 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.409Z,1557187374.409 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-05-07T00:02:54.409Z,1557187374.409 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.409Z,1557187374.409 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1323
2019-05-07T00:02:54.685Z,1557187374.685 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:54.685Z,1557187374.685 [WetLabsBB2FL](INFO): Powering down
2019-05-07T00:02:54.686Z,1557187374.686 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.694Z,1557187374.694 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-05-07T00:02:54.694Z,1557187374.694 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:54.694Z,1557187374.694 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1324
2019-05-07T00:02:55.069Z,1557187375.069 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:55.069Z,1557187375.069 [CTD_Seabird](INFO): Powering down
2019-05-07T00:02:55.081Z,1557187375.081 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.097Z,1557187375.097 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-05-07T00:02:55.098Z,1557187375.098 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.098Z,1557187375.098 [logger](INFO): Join timeout helper Thread ID is 1325
2019-05-07T00:02:55.141Z,1557187375.141 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:55.141Z,1557187375.141 [logger ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.157Z,1557187375.157 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-05-07T00:02:55.158Z,1557187375.158 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.158Z,1557187375.158 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-05-07T00:02:55.158Z,1557187375.158 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.158Z,1557187375.158 [controlThread](INFO): Join timeout helper Thread ID is 1326
2019-05-07T00:02:55.449Z,1557187375.449 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-05-07T00:02:55.449Z,1557187375.449 [controlThread](DEBUG): Uninitializing ControlThread
2019-05-07T00:02:55.449Z,1557187375.449 [Aanderaa_O2](INFO): Powering down
2019-05-07T00:02:55.451Z,1557187375.451 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-05-07T00:02:55.452Z,1557187375.452 [NAL9602](INFO): Powering down
2019-05-07T00:02:55.453Z,1557187375.453 [PNI_TCM](INFO): Powering down
2019-05-07T00:02:55.454Z,1557187375.454 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-05-07T00:02:55.455Z,1557187375.455 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-05-07T00:02:55.455Z,1557187375.455 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-05-07T00:02:55.456Z,1557187375.456 [MissionManager](INFO): Uninitializing Mission Default
2019-05-07T00:02:55.456Z,1557187375.456 [Default] Stopped
2019-05-07T00:02:55.456Z,1557187375.456 [Default](DEBUG): Aggregate::uninitialize Default
2019-05-07T00:02:55.456Z,1557187375.456 [Default:B.GoToSurface] Stopped
2019-05-07T00:02:55.456Z,1557187375.456 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-05-07T00:02:55.456Z,1557187375.456 [Default:CheckIn] Stopped
2019-05-07T00:02:55.456Z,1557187375.456 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-05-07T00:02:55.456Z,1557187375.456 [Default:CheckIn:Read_GPS] Stopped
2019-05-07T00:02:55.459Z,1557187375.459 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-05-07T00:02:55.459Z,1557187375.459 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-05-07T00:02:55.459Z,1557187375.459 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-05-07T00:02:55.460Z,1557187375.460 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-05-07T00:02:55.460Z,1557187375.460 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-05-07T00:02:55.460Z,1557187375.460 [BuoyancyServo](INFO): Powering down
2019-05-07T00:02:55.473Z,1557187375.473 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-05-07T00:02:55.473Z,1557187375.473 [ElevatorServo](INFO): Powering down
2019-05-07T00:02:55.474Z,1557187375.474 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-05-07T00:02:55.474Z,1557187375.474 [MassServo](INFO): Powering down
2019-05-07T00:02:55.475Z,1557187375.475 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-05-07T00:02:55.475Z,1557187375.475 [RudderServo](INFO): Powering down
2019-05-07T00:02:55.476Z,1557187375.476 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-05-07T00:02:55.476Z,1557187375.476 [ThrusterServo](INFO): Powering down
2019-05-07T00:02:55.477Z,1557187375.477 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-05-07T00:02:55.477Z,1557187375.477 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-05-07T00:02:55.477Z,1557187375.477 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-05-07T00:02:55.477Z,1557187375.477 [CBIT](DEBUG): Powering off loads.
2019-05-07T00:02:55.489Z,1557187375.489 [CBIT](DEBUG): Disabling WDT.
2019-05-07T00:02:55.501Z,1557187375.501 [CBIT](DEBUG): Opening all GF detection circuits.
2019-05-07T00:02:55.501Z,1557187375.501 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.544Z,1557187375.544 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.549Z,1557187375.549 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.610Z,1557187375.610 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.614Z,1557187375.614 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.663Z,1557187375.663 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-05-07T00:02:55.716Z,1557187375.716 [logger ThreadHandler](INFO): Thread cancelled.