2018-11-06T20:33:03.548Z,1541536383.548 [Supervisor](DEBUG): Initializing supervisor.
2018-11-06T20:33:03.551Z,1541536383.551 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-11-06T20:33:03.552Z,1541536383.552 [SyncHandler](INFO): Protected caller Thread ID is 2938
2018-11-06T20:33:03.552Z,1541536383.552 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-11-06T20:33:03.553Z,1541536383.553 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-11-06T20:33:03.553Z,1541536383.553 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2939
2018-11-06T20:33:03.556Z,1541536383.556 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-11-06T20:33:03.568Z,1541536383.568 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-11-06T20:33:03.569Z,1541536383.569 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-11-06T20:33:03.569Z,1541536383.569 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2940
2018-11-06T20:33:03.570Z,1541536383.570 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-11-06T20:33:03.571Z,1541536383.571 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-11-06T20:33:03.571Z,1541536383.571 [logger ThreadHandler](INFO): Protected caller Thread ID is 2941
2018-11-06T20:33:03.573Z,1541536383.573 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-11-06T20:33:03.573Z,1541536383.573 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-11-06T20:33:03.575Z,1541536383.575 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-11-06T20:33:03.676Z,1541536383.676 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-11-06T20:33:03.676Z,1541536383.676 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-11-06T20:33:03.778Z,1541536383.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-11-06T20:33:03.778Z,1541536383.778 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-11-06T20:33:03.987Z,1541536383.987 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-11-06T20:33:03.988Z,1541536383.988 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-11-06T20:33:04.453Z,1541536384.453 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-11-06T20:33:04.453Z,1541536384.453 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-11-06T20:33:05.186Z,1541536385.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-11-06T20:33:05.187Z,1541536385.187 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-11-06T20:33:05.647Z,1541536385.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-11-06T20:33:05.647Z,1541536385.647 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-11-06T20:33:06.114Z,1541536386.114 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-11-06T20:33:06.114Z,1541536386.114 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-11-06T20:33:06.566Z,1541536386.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-11-06T20:33:06.567Z,1541536386.567 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-11-06T20:33:06.649Z,1541536386.649 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-11-06T20:33:06.975Z,1541536386.975 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-11-06T20:33:06.976Z,1541536386.976 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-11-06T20:33:07.127Z,1541536387.127 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-11-06T20:33:07.128Z,1541536387.128 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-11-06T20:33:07.344Z,1541536387.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-11-06T20:33:07.344Z,1541536387.344 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-11-06T20:33:07.552Z,1541536387.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-11-06T20:33:07.553Z,1541536387.553 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-11-06T20:33:07.652Z,1541536387.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-11-06T20:33:07.653Z,1541536387.653 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-11-06T20:33:07.800Z,1541536387.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-11-06T20:33:07.801Z,1541536387.801 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-11-06T20:33:07.802Z,1541536387.802 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-11-06T20:33:08.032Z,1541536388.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-11-06T20:33:08.032Z,1541536388.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-11-06T20:33:08.139Z,1541536388.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-11-06T20:33:08.291Z,1541536388.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-11-06T20:33:08.385Z,1541536388.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-11-06T20:33:08.471Z,1541536388.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-11-06T20:33:08.629Z,1541536388.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-11-06T20:33:08.827Z,1541536388.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-11-06T20:33:08.927Z,1541536388.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-11-06T20:33:09.104Z,1541536389.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-11-06T20:33:09.329Z,1541536389.329 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-11-06T20:33:09.575Z,1541536389.575 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-11-06T20:33:09.575Z,1541536389.575 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-11-06T20:33:09.578Z,1541536389.578 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-11-06T20:33:09.970Z,1541536389.970 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-11-06T20:33:09.971Z,1541536389.971 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-11-06T20:33:09.995Z,1541536389.995 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-11-06T20:33:09.996Z,1541536389.996 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-11-06T20:33:10.097Z,1541536390.097 [DepthRateCalculator] Loaded
2018-11-06T20:33:10.097Z,1541536390.097 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-11-06T20:33:10.104Z,1541536390.104 [PitchRateCalculator] Loaded
2018-11-06T20:33:10.104Z,1541536390.104 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-11-06T20:33:10.120Z,1541536390.120 [SpeedCalculator] Loaded
2018-11-06T20:33:10.120Z,1541536390.120 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-11-06T20:33:10.207Z,1541536390.207 [TempGradientCalculator] Loaded
2018-11-06T20:33:10.207Z,1541536390.207 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-11-06T20:33:10.213Z,1541536390.213 [YawRateCalculator] Loaded
2018-11-06T20:33:10.213Z,1541536390.213 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-11-06T20:33:10.253Z,1541536390.253 [ElevatorOffsetCalculator] Loaded
2018-11-06T20:33:10.253Z,1541536390.253 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-11-06T20:33:10.253Z,1541536390.253 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-11-06T20:33:10.254Z,1541536390.254 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-11-06T20:33:10.279Z,1541536390.279 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-11-06T20:33:10.279Z,1541536390.279 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-11-06T20:33:10.645Z,1541536390.645 [AcousticModem_Benthos_ATM900] Loaded
2018-11-06T20:33:10.645Z,1541536390.645 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-11-06T20:33:10.717Z,1541536390.717 [DataOverHttps] Loaded
2018-11-06T20:33:10.718Z,1541536390.718 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-11-06T20:33:10.731Z,1541536390.731 [Depth_Keller] Loaded
2018-11-06T20:33:10.731Z,1541536390.731 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-11-06T20:33:10.736Z,1541536390.736 [DropWeight] Loaded
2018-11-06T20:33:10.736Z,1541536390.736 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2018-11-06T20:33:10.834Z,1541536390.834 [NAL9602] Loaded
2018-11-06T20:33:10.835Z,1541536390.835 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-11-06T20:33:10.850Z,1541536390.850 [Onboard] Loaded
2018-11-06T20:33:10.850Z,1541536390.850 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-11-06T20:33:10.861Z,1541536390.861 [Radio_Surface] Loaded
2018-11-06T20:33:10.861Z,1541536390.861 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-11-06T20:33:10.862Z,1541536390.862 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407C24E0
2018-11-06T20:33:10.862Z,1541536390.862 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3020
2018-11-06T20:33:10.909Z,1541536390.909 [PNI_TCM] Loaded
2018-11-06T20:33:10.909Z,1541536390.909 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-11-06T20:33:10.971Z,1541536390.971 [Rowe_600LCM] Loaded
2018-11-06T20:33:10.971Z,1541536390.971 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-11-06T20:33:10.972Z,1541536390.972 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407F24E0
2018-11-06T20:33:10.973Z,1541536390.973 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 3021
2018-11-06T20:33:12.715Z,1541536392.715 [BPC1] Loaded
2018-11-06T20:33:12.715Z,1541536392.715 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-11-06T20:33:12.716Z,1541536392.716 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-11-06T20:33:12.716Z,1541536392.716 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-11-06T20:33:12.847Z,1541536392.847 [SBIT](DEBUG): Construct Startup Built In Test.
2018-11-06T20:33:12.871Z,1541536392.871 [SBIT] Loaded
2018-11-06T20:33:12.871Z,1541536392.871 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-11-06T20:33:12.872Z,1541536392.872 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-11-06T20:33:12.884Z,1541536392.884 [IBIT] Loaded
2018-11-06T20:33:12.884Z,1541536392.884 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-11-06T20:33:12.887Z,1541536392.887 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-11-06T20:33:13.035Z,1541536393.035 [CBIT] Loaded
2018-11-06T20:33:13.035Z,1541536393.035 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-11-06T20:33:13.035Z,1541536393.035 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-11-06T20:33:13.036Z,1541536393.036 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-11-06T20:33:13.194Z,1541536393.194 [Aanderaa_O2] Loaded
2018-11-06T20:33:13.194Z,1541536393.194 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-11-06T20:33:13.251Z,1541536393.251 [CTD_Seabird] Loaded
2018-11-06T20:33:13.252Z,1541536393.252 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-11-06T20:33:13.253Z,1541536393.253 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0
2018-11-06T20:33:13.253Z,1541536393.253 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3022
2018-11-06T20:33:13.271Z,1541536393.271 [ESPComponent] Loaded
2018-11-06T20:33:13.271Z,1541536393.271 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-11-06T20:33:13.285Z,1541536393.285 [PAR_Licor] Loaded
2018-11-06T20:33:13.285Z,1541536393.285 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-11-06T20:33:13.334Z,1541536393.334 [WetLabsBB2FL] Loaded
2018-11-06T20:33:13.334Z,1541536393.334 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-11-06T20:33:13.335Z,1541536393.335 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0
2018-11-06T20:33:13.335Z,1541536393.335 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3023
2018-11-06T20:33:13.336Z,1541536393.336 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-11-06T20:33:13.337Z,1541536393.337 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-11-06T20:33:13.578Z,1541536393.578 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-11-06T20:33:13.578Z,1541536393.578 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-11-06T20:33:13.646Z,1541536393.646 [VerticalControl](DEBUG): Construct VerticalControl.
2018-11-06T20:33:13.742Z,1541536393.742 [VerticalControl] Loaded
2018-11-06T20:33:13.742Z,1541536393.742 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-11-06T20:33:13.743Z,1541536393.743 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-11-06T20:33:13.804Z,1541536393.804 [HorizontalControl] Loaded
2018-11-06T20:33:13.804Z,1541536393.804 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-11-06T20:33:13.805Z,1541536393.805 [SpeedControl](DEBUG): Construct SpeedControl.
2018-11-06T20:33:13.810Z,1541536393.810 [SpeedControl] Loaded
2018-11-06T20:33:13.811Z,1541536393.811 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-11-06T20:33:13.811Z,1541536393.811 [LoopControl](DEBUG): Construct LoopControl.
2018-11-06T20:33:13.812Z,1541536393.812 [LoopControl] Loaded
2018-11-06T20:33:13.812Z,1541536393.812 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-11-06T20:33:13.812Z,1541536393.812 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-11-06T20:33:13.813Z,1541536393.813 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-11-06T20:33:13.877Z,1541536393.877 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-11-06T20:33:13.878Z,1541536393.878 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-11-06T20:33:13.977Z,1541536393.977 [BuoyancyServo] Loaded
2018-11-06T20:33:13.977Z,1541536393.977 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-11-06T20:33:13.989Z,1541536393.989 [ElevatorServo] Loaded
2018-11-06T20:33:13.989Z,1541536393.989 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-11-06T20:33:13.000Z,1541536394.000 [MassServo] Loaded
2018-11-06T20:33:14.000Z,1541536394.000 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-11-06T20:33:14.012Z,1541536394.012 [RudderServo] Loaded
2018-11-06T20:33:14.012Z,1541536394.012 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-11-06T20:33:14.023Z,1541536394.023 [ThrusterServo] Loaded
2018-11-06T20:33:14.023Z,1541536394.023 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-11-06T20:33:14.024Z,1541536394.024 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-11-06T20:33:14.024Z,1541536394.024 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-11-06T20:33:14.115Z,1541536394.115 [DeadReckonUsingSpeedCalculator] Loaded
2018-11-06T20:33:14.115Z,1541536394.115 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-11-06T20:33:14.131Z,1541536394.131 [NavChart] Loaded
2018-11-06T20:33:14.131Z,1541536394.131 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-11-06T20:33:14.135Z,1541536394.135 [UniversalFixResidualReporter] Loaded
2018-11-06T20:33:14.136Z,1541536394.136 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-11-06T20:33:14.136Z,1541536394.136 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-11-06T20:33:14.151Z,1541536394.151 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-11-06T20:33:14.152Z,1541536394.152 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-11-06T20:33:14.162Z,1541536394.162 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-11-06T20:33:14.163Z,1541536394.163 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A464E0
2018-11-06T20:33:14.163Z,1541536394.163 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3024
2018-11-06T20:33:14.168Z,1541536394.168 [Supervisor](INFO): Main Thread ID is 795
2018-11-06T20:33:14.168Z,1541536394.168 [Supervisor](DEBUG): Running supervisor.
2018-11-06T20:33:14.169Z,1541536394.169 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3025
2018-11-06T20:33:14.171Z,1541536394.171 [controlThread ThreadHandler](INFO): Handler Thread ID is 3026
2018-11-06T20:33:14.171Z,1541536394.171 [controlThread](DEBUG): Initializing ControlThread
2018-11-06T20:33:14.172Z,1541536394.172 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T20:33:14.173Z,1541536394.173 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-11-06T20:33:14.173Z,1541536394.173 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-11-06T20:33:14.173Z,1541536394.173 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-11-06T20:33:14.174Z,1541536394.174 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-11-06T20:33:14.174Z,1541536394.174 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-11-06T20:33:14.180Z,1541536394.180 [SBIT](INFO): Initialize SBIT Component.
2018-11-06T20:33:14.181Z,1541536394.181 [SBIT](IMPORTANT): git: 2018-10-09-34-gf593050
2018-11-06T20:33:14.181Z,1541536394.181 [SBIT](INFO): git hash: f5930504aac459d36ba66e787e98c9e35813b4f6
2018-11-06T20:33:14.181Z,1541536394.181 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-11-06T20:33:14.182Z,1541536394.182 [SBIT](IMPORTANT): Kernel Version:#2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-11-06T20:33:14.183Z,1541536394.183 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2018-11-06T20:33:14.183Z,1541536394.183 [IBIT](INFO): Initialize IBIT Component.
2018-11-06T20:33:14.184Z,1541536394.184 [CBIT](DEBUG): Initialize CBIT Component.
2018-11-06T20:33:14.184Z,1541536394.184 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-11-06T20:33:14.185Z,1541536394.185 [logger ThreadHandler](INFO): Handler Thread ID is 3027
2018-11-06T20:33:14.203Z,1541536394.203 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3028
2018-11-06T20:33:14.209Z,1541536394.209 [Radio_Surface](INFO): Powering up
2018-11-06T20:33:14.214Z,1541536394.214 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 3029
2018-11-06T20:33:14.215Z,1541536394.215 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:33:14.216Z,1541536394.216 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:33:14.225Z,1541536394.225 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:33:14.225Z,1541536394.225 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:33:14.231Z,1541536394.231 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3030
2018-11-06T20:33:14.231Z,1541536394.231 [CTD_Seabird](INFO): Initializing
2018-11-06T20:33:14.232Z,1541536394.232 [CTD_Seabird](INFO): Checking LCM
2018-11-06T20:33:14.232Z,1541536394.232 [CTD_Seabird](INFO): LCM OK
2018-11-06T20:33:14.232Z,1541536394.232 [CTD_Seabird](INFO): Powering up
2018-11-06T20:33:14.238Z,1541536394.238 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3031
2018-11-06T20:33:14.240Z,1541536394.240 [WetLabsBB2FL](INFO): Powering down
2018-11-06T20:33:14.267Z,1541536394.267 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3032
2018-11-06T20:33:14.270Z,1541536394.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-11-06T20:33:14.270Z,1541536394.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-11-06T20:33:14.270Z,1541536394.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-11-06T20:33:14.271Z,1541536394.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-11-06T20:33:14.271Z,1541536394.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-11-06T20:33:14.271Z,1541536394.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-11-06T20:33:14.271Z,1541536394.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-11-06T20:33:14.271Z,1541536394.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-11-06T20:33:14.272Z,1541536394.272 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-11-06T20:33:14.273Z,1541536394.273 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-11-06T20:33:14.273Z,1541536394.273 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-11-06T20:33:14.294Z,1541536394.294 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-11-06T20:33:14.296Z,1541536394.296 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-11-06T20:33:14.297Z,1541536394.297 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-11-06T20:33:14.298Z,1541536394.298 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-11-06T20:33:14.299Z,1541536394.299 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:33:14.300Z,1541536394.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:33:14.300Z,1541536394.300 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:33:14.301Z,1541536394.301 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-11-06T20:33:14.301Z,1541536394.301 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-11-06T20:33:14.302Z,1541536394.302 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-11-06T20:33:14.326Z,1541536394.326 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T20:33:14.354Z,1541536394.354 [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>


2018-11-06T20:33:14.378Z,1541536394.378 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-11-06T20:33:14.449Z,1541536394.449 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-11-06T20:33:14.450Z,1541536394.450 [Default:A.Wait](DEBUG): Construct Wait.
2018-11-06T20:33:14.452Z,1541536394.452 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-11-06T20:33:14.502Z,1541536394.502 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-11-06T20:33:14.505Z,1541536394.505 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-11-06T20:33:14.521Z,1541536394.521 [Default:E.Execute](DEBUG): Construct Execute.
2018-11-06T20:33:14.525Z,1541536394.525 [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>


2018-11-06T20:33:14.541Z,1541536394.541 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-11-06T20:33:14.568Z,1541536394.568 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-11-06T20:33:14.568Z,1541536394.568 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-11-06T20:33:14.592Z,1541536394.592 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-11-06T20:33:14.639Z,1541536394.639 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2018-11-06T20:33:14.816Z,1541536394.816 [ESPComponent](INFO): powering down ESP
2018-11-06T20:33:15.167Z,1541536395.167 [DepthRateCalculator](ERROR): Depth measurement is not active
2018-11-06T20:33:15.362Z,1541536395.362 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:15.370Z,1541536395.370 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T20:33:15.385Z,1541536395.385 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:15.390Z,1541536395.390 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-11-06T20:33:15.412Z,1541536395.412 [MassServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:15.419Z,1541536395.419 [MassServo](DEBUG): Initializing MassServo.
2018-11-06T20:33:15.433Z,1541536395.433 [RudderServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:15.439Z,1541536395.439 [RudderServo](DEBUG): Initializing RudderServo.
2018-11-06T20:33:15.449Z,1541536395.449 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:15.454Z,1541536395.454 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-11-06T20:33:15.692Z,1541536395.692 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2018-11-06T20:33:15.692Z,1541536395.692 [DropWeight] Hardware Fault, FailCount= 1
2018-11-06T20:33:15.692Z,1541536395.692 [DropWeight](ERROR): Hardware Fault
2018-11-06T20:33:15.791Z,1541536395.791 [CommandLine](FAULT): Scheduling is paused
2018-11-06T20:33:15.791Z,1541536395.791 [CBIT](INFO): Critical error at 20181106T203315
2018-11-06T20:33:15.791Z,1541536395.791 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-11-06T20:33:15.794Z,1541536395.794 [CBIT](ERROR): Hardware Fault in component: DropWeight
2018-11-06T20:33:15.794Z,1541536395.794 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2018-11-06T20:33:16.342Z,1541536396.342 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-11-06T20:33:16.342Z,1541536396.342 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-11-06T20:33:16.342Z,1541536396.342 [BuoyancyServo] Communications Fault, FailCount= 1
2018-11-06T20:33:16.342Z,1541536396.342 [BuoyancyServo](ERROR): Communications Fault
2018-11-06T20:33:16.548Z,1541536396.548 [CBIT](INFO): Critical error at 20181106T203315
2018-11-06T20:33:16.550Z,1541536396.550 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-11-06T20:33:16.659Z,1541536396.659 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T20:33:16.661Z,1541536396.661 [BuoyancyServo](INFO): Powering down
2018-11-06T20:33:18.465Z,1541536398.465 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:33:18.549Z,1541536398.549 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:33:18.647Z,1541536398.647 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:33:18.648Z,1541536398.648 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:33:18.648Z,1541536398.648 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:33:19.162Z,1541536399.162 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-11-06T20:33:19.162Z,1541536399.162 [BuoyancyServo] No Fault, FailCount= 1
2018-11-06T20:33:19.416Z,1541536399.416 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-11-06T20:33:19.416Z,1541536399.416 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-11-06T20:33:19.780Z,1541536399.780 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:33:19.977Z,1541536399.977 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-11-06T20:33:19.979Z,1541536399.979 [CTD_Seabird](INFO): LCM subscribed to channel:seabird_gpctd_t.seabird-gpctd
2018-11-06T20:33:20.603Z,1541536400.603 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:33:20.603Z,1541536400.603 [CTD_Seabird](INFO): Powering down
2018-11-06T20:33:22.159Z,1541536402.159 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-11-06T20:33:22.179Z,1541536402.179 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:33:22.179Z,1541536402.179 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:33:22.179Z,1541536402.179 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:33:22.275Z,1541536402.275 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:33:22.580Z,1541536402.580 [PNI_TCM](INFO): Powering down
2018-11-06T20:33:23.360Z,1541536403.360 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-11-06T20:33:23.361Z,1541536403.361 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-11-06T20:33:23.361Z,1541536403.361 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-11-06T20:33:23.362Z,1541536403.362 [AcousticModem_Benthos_ATM900](DEBUG): Oct 25 2018 21:15:19 
2018-11-06T20:33:23.759Z,1541536403.759 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-11-06T20:33:23.846Z,1541536403.846 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:33:23.846Z,1541536403.846 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:33:24.234Z,1541536404.234 [CBIT](CRITICAL): Environmental Failure. Press:14.770781 PSI. Humidity:37%. Temp:27 C. ABORTING MISSION
2018-11-06T20:33:24.559Z,1541536404.559 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-11-06T20:33:24.637Z,1541536404.637 [CBIT](INFO): Critical error at 20181106T203324
2018-11-06T20:33:24.959Z,1541536404.959 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-11-06T20:33:24.959Z,1541536404.959 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-11-06T20:33:24.960Z,1541536404.960 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-11-06T20:33:24.991Z,1541536404.991 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:33:24.991Z,1541536404.991 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:33:24.991Z,1541536404.991 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:33:25.058Z,1541536405.058 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:33:25.359Z,1541536405.359 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-11-06T20:33:25.389Z,1541536405.389 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:33:25.759Z,1541536405.759 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-11-06T20:33:25.760Z,1541536405.760 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-11-06T20:33:26.159Z,1541536406.159 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-11-06T20:33:26.270Z,1541536406.270 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:33:26.278Z,1541536406.278 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:33:31.380Z,1541536411.380 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:33:31.380Z,1541536411.380 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:33:31.380Z,1541536411.380 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:33:31.421Z,1541536411.421 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:33:31.779Z,1541536411.779 [PNI_TCM](INFO): Powering down
2018-11-06T20:33:33.057Z,1541536413.057 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:33:33.057Z,1541536413.057 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:33:36.981Z,1541536416.981 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:33:36.981Z,1541536416.981 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:33:36.981Z,1541536416.981 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:33:37.053Z,1541536417.053 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:33:37.382Z,1541536417.382 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:33:38.226Z,1541536418.226 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:33:38.226Z,1541536418.226 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:33:38.651Z,1541536418.651 [SBIT](IMPORTANT): Beginning Startup BIT
2018-11-06T20:33:38.671Z,1541536418.671 [CBIT](IMPORTANT): Beginning ground fault scan
2018-11-06T20:33:40.584Z,1541536420.584 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:33:40.584Z,1541536420.584 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:33:40.584Z,1541536420.584 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:33:41.084Z,1541536421.084 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:33:41.094Z,1541536421.094 [NAL9602](INFO): Powering up NAL9602
2018-11-06T20:33:41.145Z,1541536421.145 [PNI_TCM](INFO): Powering down
2018-11-06T20:33:42.468Z,1541536422.468 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:33:42.468Z,1541536422.468 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:33:48.719Z,1541536428.719 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:33:48.719Z,1541536428.719 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:33:48.719Z,1541536428.719 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:33:48.812Z,1541536428.812 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:33:48.812Z,1541536428.812 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:33:49.226Z,1541536429.226 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:33:49.620Z,1541536429.620 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007237
CHAN A1 (24V): -0.011098
CHAN A2 (12V): -0.006044
CHAN A3 (5V): -0.002386
CHAN B0 (3.3V): -0.001496
CHAN B1 (3.15aV): -0.001202
CHAN B2 (3.15bV): -0.001394
CHAN B3 (GND): 0.000044
OPEN: 0.004370
Full Scale Calc: 4.765 mA, -1.589 mA
2018-11-06T20:33:49.912Z,1541536429.912 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:33:49.912Z,1541536429.912 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:33:49.912Z,1541536429.912 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:33:50.020Z,1541536430.020 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:33:50.312Z,1541536430.312 [PNI_TCM](INFO): Powering down
2018-11-06T20:33:51.604Z,1541536431.604 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:33:51.604Z,1541536431.604 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:33:52.296Z,1541536432.296 [NAL9602](INFO): NAL9602 initialized
2018-11-06T20:33:58.964Z,1541536438.964 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:33:58.964Z,1541536438.964 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:33:58.964Z,1541536438.964 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:33:59.159Z,1541536439.159 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:33:59.160Z,1541536439.160 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:33:59.376Z,1541536439.376 [PNI_TCM](INFO): Powering down
2018-11-06T20:33:59.725Z,1541536439.725 [CBIT](INFO): Critical error at 20181106T203359
2018-11-06T20:34:21.525Z,1541536461.525 [BPC1](FAULT): Failed to parse data from all battery packs.
2018-11-06T20:34:21.525Z,1541536461.525 [BPC1] Data Fault, FailCount= 1
2018-11-06T20:34:21.525Z,1541536461.525 [BPC1](ERROR): Data Fault
2018-11-06T20:34:21.635Z,1541536461.635 [CBIT](ERROR): Data Fault in component: BPC1
2018-11-06T20:34:32.711Z,1541536472.711 [SBIT](IMPORTANT): SBIT PASSED
2018-11-06T20:34:32.763Z,1541536472.763 [CommandLine](IMPORTANT): got command configSet list
2018-11-06T20:34:32.763Z,1541536472.763 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-11-06T20:34:32.764Z,1541536472.764 [CommandLine](IMPORTANT): PAR_Licor.loadAtStartup=1 bool;

2018-11-06T20:34:33.105Z,1541536473.105 [MissionManager](IMPORTANT): Started mission Startup
2018-11-06T20:34:33.105Z,1541536473.105 [Startup] Running Loop=1
2018-11-06T20:34:33.105Z,1541536473.105 [Startup](DEBUG): Aggregate::initialize Startup
2018-11-06T20:34:33.105Z,1541536473.105 [Startup:A.GoToSurface] Running Loop=1
2018-11-06T20:34:33.105Z,1541536473.105 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T20:34:33.106Z,1541536473.106 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T20:34:33.106Z,1541536473.106 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T20:34:33.107Z,1541536473.107 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T20:34:33.107Z,1541536473.107 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T20:34:33.107Z,1541536473.107 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T20:34:33.113Z,1541536473.113 [Startup:StartupSatComms] Running Loop=1
2018-11-06T20:34:33.114Z,1541536473.114 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-11-06T20:34:33.114Z,1541536473.114 [Startup:StartupSatComms:A] Running Loop=1
2018-11-06T20:34:33.502Z,1541536473.502 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-11-06T20:35:14.301Z,1541536514.301 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:35:14.301Z,1541536514.301 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-11-06T20:35:14.301Z,1541536514.301 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:35:14.315Z,1541536514.315 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:35:14.322Z,1541536514.322 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:35:14.322Z,1541536514.322 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-11-06T20:35:14.322Z,1541536514.322 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:35:14.719Z,1541536514.719 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:35:14.720Z,1541536514.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:35:14.720Z,1541536514.720 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-11-06T20:35:14.726Z,1541536514.726 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:35:14.871Z,1541536514.871 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:35:15.111Z,1541536515.111 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:35:15.112Z,1541536515.112 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:35:15.112Z,1541536515.112 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:35:16.320Z,1541536516.320 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:35:16.320Z,1541536516.320 [Rowe_600LCM] No Fault, FailCount= 1
2018-11-06T20:35:16.487Z,1541536516.487 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:35:16.488Z,1541536516.488 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:35:16.488Z,1541536516.488 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:35:16.488Z,1541536516.488 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:35:20.607Z,1541536520.607 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:35:20.709Z,1541536520.709 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:35:20.710Z,1541536520.710 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:35:20.711Z,1541536520.711 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:35:20.712Z,1541536520.712 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:35:33.125Z,1541536533.125 [Startup:StartupSatComms:A](INFO): Timed out from 2018-11-06T20:34:33.1Z
2018-11-06T20:35:33.125Z,1541536533.125 [Startup:StartupSatComms:A] Stopped
2018-11-06T20:35:33.125Z,1541536533.125 [Startup:StartupSatComms:B] Running Loop=1
2018-11-06T20:35:33.530Z,1541536533.530 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T20:36:19.031Z,1541536579.031 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:36:19.031Z,1541536579.031 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:36:19.844Z,1541536579.844 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004087
2018-11-06T20:36:24.267Z,1541536584.267 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20181106T201203/Courier0007.lzma
2018-11-06T20:36:25.048Z,1541536585.048 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Courier0007.lzma.bak
2018-11-06T20:36:25.049Z,1541536585.049 [DataOverHttps](INFO): SBD MOMSN=8798462
2018-11-06T20:36:29.616Z,1541536589.616 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:36:29.616Z,1541536589.616 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:36:29.616Z,1541536589.616 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:36:29.635Z,1541536589.635 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:36:29.846Z,1541536589.846 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:36:30.499Z,1541536590.499 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:36:30.499Z,1541536590.499 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:36:34.381Z,1541536594.381 [Startup:StartupSatComms:B](INFO): Timed out from 2018-11-06T20:35:33.1Z
2018-11-06T20:36:34.381Z,1541536594.381 [Startup:StartupSatComms:B] Stopped
2018-11-06T20:36:34.382Z,1541536594.382 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-11-06T20:36:34.382Z,1541536594.382 [Startup:StartupSatComms] Stopped
2018-11-06T20:36:34.382Z,1541536594.382 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-11-06T20:36:34.383Z,1541536594.383 [Startup](INFO): Completed Startup
2018-11-06T20:36:34.383Z,1541536594.383 [MissionManager](INFO): Startup is completed.
2018-11-06T20:36:34.383Z,1541536594.383 [MissionManager](INFO): Uninitializing Mission Startup
2018-11-06T20:36:34.383Z,1541536594.383 [Startup] Stopped
2018-11-06T20:36:34.383Z,1541536594.383 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-11-06T20:36:34.383Z,1541536594.383 [Startup:A.GoToSurface] Stopped
2018-11-06T20:36:34.383Z,1541536594.383 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T20:36:34.405Z,1541536594.405 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T203147/Courier0000.lzma
2018-11-06T20:36:34.511Z,1541536594.511 [MissionManager](IMPORTANT): Started mission Default
2018-11-06T20:36:34.511Z,1541536594.511 [Default] Running Loop=1
2018-11-06T20:36:34.511Z,1541536594.511 [Default](DEBUG): Aggregate::initialize Default
2018-11-06T20:36:34.511Z,1541536594.511 [Default:B.GoToSurface] Running Loop=1
2018-11-06T20:36:34.512Z,1541536594.512 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-11-06T20:36:34.512Z,1541536594.512 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-11-06T20:36:34.512Z,1541536594.512 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-11-06T20:36:34.512Z,1541536594.512 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-11-06T20:36:34.513Z,1541536594.513 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-11-06T20:36:34.513Z,1541536594.513 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-11-06T20:36:34.513Z,1541536594.513 [Default:A.Wait] Running Loop=1
2018-11-06T20:36:34.514Z,1541536594.514 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-11-06T20:36:35.204Z,1541536595.204 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203147/Courier0000.lzma.bak
2018-11-06T20:36:35.204Z,1541536595.204 [DataOverHttps](INFO): SBD MOMSN=8798466
2018-11-06T20:36:41.185Z,1541536601.185 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:36:41.185Z,1541536601.185 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:36:41.185Z,1541536601.185 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:36:41.206Z,1541536601.206 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:36:41.702Z,1541536601.702 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:36:42.409Z,1541536602.409 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:36:42.409Z,1541536602.409 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:36:47.603Z,1541536607.603 [Default:A.Wait](INFO): Done Waiting.
2018-11-06T20:36:47.604Z,1541536607.604 [Default:A.Wait] Stopped
2018-11-06T20:36:47.604Z,1541536607.604 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-11-06T20:36:48.001Z,1541536608.001 [Default:CheckIn] Running Loop=1
2018-11-06T20:36:48.001Z,1541536608.001 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-11-06T20:36:48.001Z,1541536608.001 [Default:CheckIn:Read_GPS] Running Loop=1
2018-11-06T20:36:48.396Z,1541536608.396 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-11-06T20:36:53.191Z,1541536613.191 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:36:53.191Z,1541536613.191 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:36:53.191Z,1541536613.191 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:36:53.216Z,1541536613.216 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:36:53.217Z,1541536613.217 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:36:53.706Z,1541536613.706 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:37:15.194Z,1541536635.194 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:37:15.194Z,1541536635.194 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-11-06T20:37:15.194Z,1541536635.194 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:37:15.207Z,1541536635.207 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:37:15.635Z,1541536635.635 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:37:15.635Z,1541536635.635 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-11-06T20:37:16.007Z,1541536636.007 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:37:16.008Z,1541536636.008 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:37:16.008Z,1541536636.008 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:37:17.218Z,1541536637.218 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:37:17.218Z,1541536637.218 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-11-06T20:37:17.218Z,1541536637.218 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:37:17.612Z,1541536637.612 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:37:17.622Z,1541536637.622 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:37:17.742Z,1541536637.742 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:37:19.212Z,1541536639.212 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:37:19.212Z,1541536639.212 [Rowe_600LCM] No Fault, FailCount= 2
2018-11-06T20:37:19.358Z,1541536639.358 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:37:19.359Z,1541536639.359 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:37:19.359Z,1541536639.359 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:37:19.359Z,1541536639.359 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:37:23.483Z,1541536643.483 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:37:23.551Z,1541536643.551 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:37:23.553Z,1541536643.553 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:37:23.559Z,1541536643.559 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:37:23.560Z,1541536643.560 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:38:55.120Z,1541536735.120 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-11-06T20:38:59.180Z,1541536739.180 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:38:59.180Z,1541536739.180 [PNI_TCM] No Fault, FailCount= 5
2018-11-06T20:39:06.737Z,1541536746.737 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:39:06.737Z,1541536746.737 [PNI_TCM] Data Fault, FailCount= 1
2018-11-06T20:39:06.738Z,1541536746.738 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:39:06.789Z,1541536746.789 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:39:07.135Z,1541536747.135 [PNI_TCM](INFO): Powering down
2018-11-06T20:39:08.368Z,1541536748.368 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:39:08.368Z,1541536748.368 [PNI_TCM] No Fault, FailCount= 1
2018-11-06T20:39:15.939Z,1541536755.939 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:39:15.939Z,1541536755.939 [PNI_TCM] Data Fault, FailCount= 2
2018-11-06T20:39:15.939Z,1541536755.939 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:39:15.964Z,1541536755.964 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:39:16.343Z,1541536756.343 [PNI_TCM](INFO): Powering down
2018-11-06T20:39:16.447Z,1541536756.447 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:39:16.447Z,1541536756.447 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-11-06T20:39:16.447Z,1541536756.447 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:39:16.460Z,1541536756.460 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:39:16.775Z,1541536756.775 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:39:16.775Z,1541536756.775 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-11-06T20:39:17.159Z,1541536757.159 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:39:17.160Z,1541536757.160 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:39:17.160Z,1541536757.160 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:39:17.566Z,1541536757.566 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:39:17.566Z,1541536757.566 [PNI_TCM] No Fault, FailCount= 2
2018-11-06T20:39:19.902Z,1541536759.902 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:39:19.902Z,1541536759.902 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-11-06T20:39:19.902Z,1541536759.902 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:39:20.004Z,1541536760.004 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:39:20.306Z,1541536760.306 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:39:20.488Z,1541536760.488 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:39:21.973Z,1541536761.973 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:39:21.973Z,1541536761.973 [Rowe_600LCM] No Fault, FailCount= 3
2018-11-06T20:39:22.102Z,1541536762.102 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:39:22.103Z,1541536762.103 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:39:22.103Z,1541536762.103 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:39:22.103Z,1541536762.103 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:39:23.581Z,1541536763.581 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:39:23.581Z,1541536763.581 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:39:25.136Z,1541536765.136 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:39:25.136Z,1541536765.136 [PNI_TCM] Data Fault, FailCount= 3
2018-11-06T20:39:25.136Z,1541536765.136 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:39:25.199Z,1541536765.199 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:39:25.539Z,1541536765.539 [PNI_TCM](INFO): Powering down
2018-11-06T20:39:26.228Z,1541536766.228 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:39:26.294Z,1541536766.294 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:39:26.295Z,1541536766.295 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:39:26.296Z,1541536766.296 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:39:26.297Z,1541536766.297 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:39:26.754Z,1541536766.754 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:39:26.766Z,1541536766.766 [PNI_TCM] No Fault, FailCount= 3
2018-11-06T20:39:34.347Z,1541536774.347 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:39:34.347Z,1541536774.347 [PNI_TCM] Data Fault, FailCount= 4
2018-11-06T20:39:34.347Z,1541536774.347 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:39:34.348Z,1541536774.348 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:39:34.348Z,1541536774.348 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:39:34.348Z,1541536774.348 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:39:34.424Z,1541536774.424 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:39:34.424Z,1541536774.424 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:39:34.746Z,1541536774.746 [PNI_TCM](INFO): Powering down
2018-11-06T20:39:34.835Z,1541536774.835 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:39:35.583Z,1541536775.583 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:39:35.583Z,1541536775.583 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:39:35.983Z,1541536775.983 [CBIT](INFO): Clearing failed state for component PNI_TCM
2018-11-06T20:39:35.983Z,1541536775.983 [PNI_TCM] No Fault, FailCount= 4
2018-11-06T20:39:43.569Z,1541536783.569 [PNI_TCM](FAULT): Failed to set parameters during initialization.
2018-11-06T20:39:43.570Z,1541536783.570 [PNI_TCM] Data Fault, FailCount= 5
2018-11-06T20:39:43.570Z,1541536783.570 [PNI_TCM](ERROR): Data Fault
2018-11-06T20:39:43.616Z,1541536783.616 [CBIT](ERROR): Data Fault in component: PNI_TCM
2018-11-06T20:39:43.617Z,1541536783.617 [CBIT](CRITICAL): Data Fault in component: PNI_TCM
2018-11-06T20:39:43.960Z,1541536783.960 [PNI_TCM](INFO): Powering down
2018-11-06T20:39:44.082Z,1541536784.082 [CBIT](INFO): Critical error at 20181106T203943
2018-11-06T20:39:45.960Z,1541536785.960 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:39:45.960Z,1541536785.960 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:39:45.960Z,1541536785.960 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:39:46.008Z,1541536786.008 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:39:46.359Z,1541536786.359 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:39:47.192Z,1541536787.192 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:39:47.193Z,1541536787.193 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:39:57.961Z,1541536797.961 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:39:57.961Z,1541536797.961 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:39:57.961Z,1541536797.961 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:39:58.023Z,1541536798.023 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:39:58.023Z,1541536798.023 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:39:58.474Z,1541536798.474 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:41:17.544Z,1541536877.544 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:41:17.544Z,1541536877.544 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-11-06T20:41:17.544Z,1541536877.544 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:41:17.557Z,1541536877.557 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:41:17.960Z,1541536877.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:41:17.960Z,1541536877.960 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-11-06T20:41:18.347Z,1541536878.347 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:41:18.347Z,1541536878.347 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:41:18.348Z,1541536878.348 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:41:22.738Z,1541536882.738 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:41:22.738Z,1541536882.738 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-11-06T20:41:22.738Z,1541536882.738 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:41:22.768Z,1541536882.768 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:41:23.142Z,1541536883.142 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:41:23.295Z,1541536883.295 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:41:24.756Z,1541536884.756 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-11-06T20:41:24.756Z,1541536884.756 [Rowe_600LCM] No Fault, FailCount= 4
2018-11-06T20:41:24.910Z,1541536884.910 [Rowe_600LCM](INFO): Initializing
2018-11-06T20:41:24.911Z,1541536884.911 [Rowe_600LCM](INFO): Checking LCM
2018-11-06T20:41:24.911Z,1541536884.911 [Rowe_600LCM](INFO): LCM OK
2018-11-06T20:41:24.911Z,1541536884.911 [Rowe_600LCM](INFO): Powering up
2018-11-06T20:41:29.035Z,1541536889.035 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-11-06T20:41:29.095Z,1541536889.095 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-11-06T20:41:29.096Z,1541536889.096 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-11-06T20:41:29.097Z,1541536889.097 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-11-06T20:41:29.098Z,1541536889.098 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-11-06T20:41:48.329Z,1541536908.329 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-11-06T20:36:48.0Z
2018-11-06T20:41:48.329Z,1541536908.329 [Default:CheckIn:Read_GPS] Stopped
2018-11-06T20:41:48.329Z,1541536908.329 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-11-06T20:41:48.729Z,1541536908.729 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-11-06T20:41:53.091Z,1541536913.091 [DataOverHttps](INFO): Sending 135 bytes from file Logs/20181106T203213/Courier0000.lzma
2018-11-06T20:41:53.888Z,1541536913.888 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203213/Courier0000.lzma.bak
2018-11-06T20:41:53.888Z,1541536913.888 [DataOverHttps](INFO): SBD MOMSN=8798478
2018-11-06T20:42:03.043Z,1541536923.043 [DataOverHttps](INFO): Sending 136 bytes from file Logs/20181106T203238/Courier0000.lzma
2018-11-06T20:42:03.840Z,1541536923.840 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203238/Courier0000.lzma.bak
2018-11-06T20:42:03.840Z,1541536923.840 [DataOverHttps](INFO): SBD MOMSN=8798483
2018-11-06T20:42:13.004Z,1541536933.004 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20181106T203303/Courier0000.lzma
2018-11-06T20:42:13.772Z,1541536933.772 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203303/Courier0000.lzma.bak
2018-11-06T20:42:13.773Z,1541536933.773 [DataOverHttps](INFO): SBD MOMSN=8798488
2018-11-06T20:42:22.960Z,1541536942.960 [DataOverHttps](INFO): Sending 69 bytes from file Logs/20181106T203303/Courier0004.lzma
2018-11-06T20:42:23.760Z,1541536943.760 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203303/Courier0004.lzma.bak
2018-11-06T20:42:23.760Z,1541536943.760 [DataOverHttps](INFO): SBD MOMSN=8798495
2018-11-06T20:42:29.148Z,1541536949.148 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:42:29.148Z,1541536949.148 [Aanderaa_O2] No Fault, FailCount= 3
2018-11-06T20:42:34.711Z,1541536954.711 [DataOverHttps](INFO): Sending 575 bytes from file Logs/20181106T201203/Express0008.lzma
2018-11-06T20:42:35.508Z,1541536955.508 [DataOverHttps](INFO): Moved sent file to Logs/20181106T201203/Express0008.lzma.bak
2018-11-06T20:42:35.508Z,1541536955.508 [DataOverHttps](INFO): SBD MOMSN=8798498
2018-11-06T20:42:40.691Z,1541536960.691 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:42:40.691Z,1541536960.691 [Aanderaa_O2] Communications Fault, FailCount= 1
2018-11-06T20:42:40.692Z,1541536960.692 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:42:40.711Z,1541536960.711 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:42:40.938Z,1541536960.938 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:42:41.573Z,1541536961.573 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:42:41.573Z,1541536961.573 [Aanderaa_O2] No Fault, FailCount= 1
2018-11-06T20:42:46.708Z,1541536966.708 [DataOverHttps](INFO): Sending 473 bytes from file Logs/20181106T203147/Express0001.lzma
2018-11-06T20:42:47.496Z,1541536967.496 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203147/Express0001.lzma.bak
2018-11-06T20:42:47.497Z,1541536967.497 [DataOverHttps](INFO): SBD MOMSN=8798536
2018-11-06T20:42:55.616Z,1541536975.616 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:42:55.616Z,1541536975.616 [Aanderaa_O2] Communications Fault, FailCount= 2
2018-11-06T20:42:55.617Z,1541536975.617 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:42:55.680Z,1541536975.680 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:42:55.862Z,1541536975.862 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:42:56.595Z,1541536976.595 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2018-11-06T20:42:56.595Z,1541536976.595 [Aanderaa_O2] No Fault, FailCount= 2
2018-11-06T20:43:01.861Z,1541536981.861 [DataOverHttps](INFO): Sending 474 bytes from file Logs/20181106T203213/Express0001.lzma
2018-11-06T20:43:02.664Z,1541536982.664 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203213/Express0001.lzma.bak
2018-11-06T20:43:02.665Z,1541536982.665 [DataOverHttps](INFO): SBD MOMSN=8798546
2018-11-06T20:43:09.847Z,1541536989.847 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2018-11-06T20:43:09.847Z,1541536989.847 [Aanderaa_O2] Communications Fault, FailCount= 3
2018-11-06T20:43:09.847Z,1541536989.847 [Aanderaa_O2](ERROR): Communications Fault
2018-11-06T20:43:09.867Z,1541536989.867 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2018-11-06T20:43:09.867Z,1541536989.867 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2018-11-06T20:43:10.078Z,1541536990.078 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:43:14.802Z,1541536994.802 [DataOverHttps](INFO): Sending 502 bytes from file Logs/20181106T203238/Express0001.lzma
2018-11-06T20:43:15.411Z,1541536995.411 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203238/Express0001.lzma.bak
2018-11-06T20:43:15.411Z,1541536995.411 [DataOverHttps](INFO): SBD MOMSN=8798556
2018-11-06T20:43:20.633Z,1541537000.633 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2018-11-06T20:43:20.634Z,1541537000.634 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-11-06T20:43:20.634Z,1541537000.634 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-11-06T20:43:20.647Z,1541537000.647 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-11-06T20:43:20.791Z,1541537000.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-11-06T20:43:20.791Z,1541537000.791 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-11-06T20:43:21.099Z,1541537001.099 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-11-06T20:43:21.100Z,1541537001.100 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-11-06T20:43:21.100Z,1541537001.100 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-11-06T20:43:25.436Z,1541537005.436 [DataOverHttps](INFO): Sending 1047 bytes from file Logs/20181106T203303/Express0001.lzma
2018-11-06T20:43:25.442Z,1541537005.442 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-11-06T20:43:25.442Z,1541537005.442 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-11-06T20:43:25.442Z,1541537005.442 [Rowe_600LCM](ERROR): Communications Fault
2018-11-06T20:43:25.539Z,1541537005.539 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-11-06T20:43:25.540Z,1541537005.540 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-11-06T20:43:25.846Z,1541537005.846 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:43:26.006Z,1541537006.006 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:43:26.213Z,1541537006.213 [DataOverHttps](INFO): Moved sent file to Logs/20181106T203303/Express0001.lzma.bak
2018-11-06T20:43:26.214Z,1541537006.214 [DataOverHttps](INFO): SBD MOMSN=8798567
2018-11-06T20:43:32.273Z,1541537012.273 [CommandLine](IMPORTANT): got command restart application
2018-11-06T20:43:33.281Z,1541537013.281 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:33.282Z,1541537013.282 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.350Z,1541537013.350 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-11-06T20:43:33.350Z,1541537013.350 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.351Z,1541537013.351 [CommandLine](INFO): Join timeout helper Thread ID is 3101
2018-11-06T20:43:33.351Z,1541537013.351 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-11-06T20:43:33.351Z,1541537013.351 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.352Z,1541537013.352 [NavChartDb](INFO): Join timeout helper Thread ID is 3102
2018-11-06T20:43:33.430Z,1541537013.430 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:33.431Z,1541537013.431 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.466Z,1541537013.466 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-11-06T20:43:33.466Z,1541537013.466 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.467Z,1541537013.467 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3103
2018-11-06T20:43:33.474Z,1541537013.474 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:33.474Z,1541537013.474 [WetLabsBB2FL](INFO): Powering down
2018-11-06T20:43:33.475Z,1541537013.475 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.476Z,1541537013.476 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-11-06T20:43:33.476Z,1541537013.476 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.476Z,1541537013.476 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3104
2018-11-06T20:43:33.810Z,1541537013.810 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:33.952Z,1541537013.952 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-11-06T20:43:33.952Z,1541537013.952 [CTD_Seabird](INFO): Powering down
2018-11-06T20:43:33.953Z,1541537013.953 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.970Z,1541537013.970 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-11-06T20:43:33.970Z,1541537013.970 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:33.971Z,1541537013.971 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 3106
2018-11-06T20:43:34.090Z,1541537014.090 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:34.090Z,1541537014.090 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-11-06T20:43:34.224Z,1541537014.224 [Rowe_600LCM](INFO): Powering down
2018-11-06T20:43:34.225Z,1541537014.225 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.243Z,1541537014.243 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-11-06T20:43:34.243Z,1541537014.243 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.244Z,1541537014.244 [Radio_Surface](INFO): Join timeout helper Thread ID is 3108
2018-11-06T20:43:34.638Z,1541537014.638 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:34.639Z,1541537014.639 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.655Z,1541537014.655 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-11-06T20:43:34.655Z,1541537014.655 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.656Z,1541537014.656 [logger](INFO): Join timeout helper Thread ID is 3109
2018-11-06T20:43:34.662Z,1541537014.662 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:34.663Z,1541537014.663 [logger ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.675Z,1541537014.675 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-11-06T20:43:34.675Z,1541537014.675 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.675Z,1541537014.675 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-11-06T20:43:34.675Z,1541537014.675 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:34.676Z,1541537014.676 [controlThread](INFO): Join timeout helper Thread ID is 3110
2018-11-06T20:43:36.343Z,1541537016.343 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-11-06T20:43:36.343Z,1541537016.343 [controlThread](DEBUG): Uninitializing ControlThread
2018-11-06T20:43:36.344Z,1541537016.344 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-11-06T20:43:36.469Z,1541537016.469 [NAL9602](INFO): Powering down
2018-11-06T20:43:36.470Z,1541537016.470 [PNI_TCM](INFO): Powering down
2018-11-06T20:43:36.471Z,1541537016.471 [Aanderaa_O2](INFO): Powering down
2018-11-06T20:43:36.474Z,1541537016.474 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-11-06T20:43:36.476Z,1541537016.476 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-11-06T20:43:36.476Z,1541537016.476 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-11-06T20:43:36.477Z,1541537016.477 [MissionManager](INFO): Uninitializing Mission Default
2018-11-06T20:43:36.477Z,1541537016.477 [Default] Stopped
2018-11-06T20:43:36.477Z,1541537016.477 [Default](DEBUG): Aggregate::uninitialize Default
2018-11-06T20:43:36.477Z,1541537016.477 [Default:B.GoToSurface] Stopped
2018-11-06T20:43:36.477Z,1541537016.477 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-11-06T20:43:36.477Z,1541537016.477 [Default:CheckIn] Stopped
2018-11-06T20:43:36.477Z,1541537016.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-11-06T20:43:36.477Z,1541537016.477 [Default:CheckIn:Read_Iridium] Stopped
2018-11-06T20:43:36.481Z,1541537016.481 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-11-06T20:43:36.481Z,1541537016.481 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-11-06T20:43:36.481Z,1541537016.481 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-11-06T20:43:36.482Z,1541537016.482 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-11-06T20:43:36.482Z,1541537016.482 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-11-06T20:43:36.482Z,1541537016.482 [BuoyancyServo](INFO): Powering down
2018-11-06T20:43:36.494Z,1541537016.494 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-11-06T20:43:36.494Z,1541537016.494 [ElevatorServo](INFO): Powering down
2018-11-06T20:43:36.495Z,1541537016.495 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-11-06T20:43:36.495Z,1541537016.495 [MassServo](INFO): Powering down
2018-11-06T20:43:36.496Z,1541537016.496 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-11-06T20:43:36.496Z,1541537016.496 [RudderServo](INFO): Powering down
2018-11-06T20:43:36.497Z,1541537016.497 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-11-06T20:43:36.497Z,1541537016.497 [ThrusterServo](INFO): Powering down
2018-11-06T20:43:36.498Z,1541537016.498 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-11-06T20:43:36.499Z,1541537016.499 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-11-06T20:43:36.499Z,1541537016.499 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-11-06T20:43:36.500Z,1541537016.500 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.588Z,1541537016.588 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.595Z,1541537016.595 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.634Z,1541537016.634 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.639Z,1541537016.639 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.687Z,1541537016.687 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-11-06T20:43:36.748Z,1541537016.748 [logger ThreadHandler](INFO): Thread cancelled.