2019-09-05T23:03:44.639Z,1567724624.639 [Supervisor](DEBUG): Initializing supervisor.
2019-09-05T23:03:44.642Z,1567724624.642 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-09-05T23:03:44.643Z,1567724624.643 [SyncHandler](INFO): Protected caller Thread ID is 4658
2019-09-05T23:03:44.643Z,1567724624.643 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-09-05T23:03:44.644Z,1567724624.644 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-09-05T23:03:44.645Z,1567724624.645 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4659
2019-09-05T23:03:44.648Z,1567724624.648 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-09-05T23:03:44.661Z,1567724624.661 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-09-05T23:03:44.662Z,1567724624.662 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-09-05T23:03:44.662Z,1567724624.662 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4660
2019-09-05T23:03:44.663Z,1567724624.663 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-09-05T23:03:44.664Z,1567724624.664 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-09-05T23:03:44.664Z,1567724624.664 [logger ThreadHandler](INFO): Protected caller Thread ID is 4661
2019-09-05T23:03:44.666Z,1567724624.666 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-09-05T23:03:44.667Z,1567724624.667 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-09-05T23:03:44.668Z,1567724624.668 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-09-05T23:03:44.817Z,1567724624.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-09-05T23:03:44.818Z,1567724624.818 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-09-05T23:03:45.389Z,1567724625.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-09-05T23:03:45.389Z,1567724625.389 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-09-05T23:03:45.488Z,1567724625.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-09-05T23:03:45.489Z,1567724625.489 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-09-05T23:03:45.593Z,1567724625.593 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-09-05T23:03:45.593Z,1567724625.593 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-09-05T23:03:45.674Z,1567724625.674 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-09-05T23:03:45.815Z,1567724625.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-09-05T23:03:45.815Z,1567724625.815 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-09-05T23:03:46.128Z,1567724626.128 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-09-05T23:03:46.129Z,1567724626.129 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-09-05T23:03:46.816Z,1567724626.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-09-05T23:03:46.817Z,1567724626.817 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-09-05T23:03:46.965Z,1567724626.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-09-05T23:03:46.966Z,1567724626.966 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-09-05T23:03:47.178Z,1567724627.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-09-05T23:03:47.178Z,1567724627.178 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-09-05T23:03:47.647Z,1567724627.647 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-09-05T23:03:47.647Z,1567724627.647 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-09-05T23:03:47.867Z,1567724627.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-09-05T23:03:47.868Z,1567724627.868 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-09-05T23:03:48.075Z,1567724628.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-09-05T23:03:48.076Z,1567724628.076 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-09-05T23:03:48.798Z,1567724628.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-09-05T23:03:48.799Z,1567724628.799 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-09-05T23:03:49.147Z,1567724629.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-09-05T23:03:49.149Z,1567724629.149 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2019-09-05T23:03:49.150Z,1567724629.150 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2019-09-05T23:03:49.233Z,1567724629.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2019-09-05T23:03:49.394Z,1567724629.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2019-09-05T23:03:49.505Z,1567724629.505 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2019-09-05T23:03:49.590Z,1567724629.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2019-09-05T23:03:49.685Z,1567724629.685 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2019-09-05T23:03:49.886Z,1567724629.886 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2019-09-05T23:03:50.115Z,1567724630.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-09-05T23:03:50.116Z,1567724630.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2019-09-05T23:03:50.210Z,1567724630.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2019-09-05T23:03:50.309Z,1567724630.309 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2019-09-05T23:03:50.431Z,1567724630.431 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2019-09-05T23:03:50.534Z,1567724630.534 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2019-09-05T23:03:50.534Z,1567724630.534 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-09-05T23:03:50.538Z,1567724630.538 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-09-05T23:03:50.608Z,1567724630.608 [VerticalControl](DEBUG): Construct VerticalControl.
2019-09-05T23:03:50.727Z,1567724630.727 [VerticalControl] Loaded
2019-09-05T23:03:50.727Z,1567724630.727 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-09-05T23:03:50.728Z,1567724630.728 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-09-05T23:03:50.800Z,1567724630.800 [HorizontalControl] Loaded
2019-09-05T23:03:50.801Z,1567724630.801 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-09-05T23:03:50.801Z,1567724630.801 [SpeedControl](DEBUG): Construct SpeedControl.
2019-09-05T23:03:50.807Z,1567724630.807 [SpeedControl] Loaded
2019-09-05T23:03:50.807Z,1567724630.807 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-09-05T23:03:50.808Z,1567724630.808 [LoopControl](DEBUG): Construct LoopControl.
2019-09-05T23:03:50.809Z,1567724630.809 [LoopControl] Loaded
2019-09-05T23:03:50.809Z,1567724630.809 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-09-05T23:03:50.810Z,1567724630.810 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-09-05T23:03:50.810Z,1567724630.810 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-09-05T23:03:50.852Z,1567724630.852 [DepthRateCalculator] Loaded
2019-09-05T23:03:50.853Z,1567724630.853 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-09-05T23:03:50.858Z,1567724630.858 [PitchRateCalculator] Loaded
2019-09-05T23:03:50.858Z,1567724630.858 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-09-05T23:03:50.874Z,1567724630.874 [SpeedCalculator] Loaded
2019-09-05T23:03:50.875Z,1567724630.875 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-09-05T23:03:50.897Z,1567724630.897 [TempGradientCalculator] Loaded
2019-09-05T23:03:50.897Z,1567724630.897 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-09-05T23:03:50.902Z,1567724630.902 [YawRateCalculator] Loaded
2019-09-05T23:03:50.902Z,1567724630.902 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-09-05T23:03:50.933Z,1567724630.933 [ElevatorOffsetCalculator] Loaded
2019-09-05T23:03:50.933Z,1567724630.933 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-09-05T23:03:50.934Z,1567724630.934 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-09-05T23:03:50.934Z,1567724630.934 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-09-05T23:03:50.967Z,1567724630.967 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-09-05T23:03:50.968Z,1567724630.968 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-09-05T23:03:51.039Z,1567724631.039 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-09-05T23:03:51.040Z,1567724631.040 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-09-05T23:03:51.338Z,1567724631.338 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-09-05T23:03:51.338Z,1567724631.338 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-09-05T23:03:51.582Z,1567724631.582 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-09-05T23:03:51.583Z,1567724631.583 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-09-05T23:03:52.233Z,1567724632.233 [AHRS_M2] Loaded
2019-09-05T23:03:52.233Z,1567724632.233 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-09-05T23:03:52.307Z,1567724632.307 [DataOverHttps] Loaded
2019-09-05T23:03:52.307Z,1567724632.307 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-09-05T23:03:52.308Z,1567724632.308 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BB4E0
2019-09-05T23:03:52.309Z,1567724632.309 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4740
2019-09-05T23:03:52.322Z,1567724632.322 [Depth_Keller] Loaded
2019-09-05T23:03:52.323Z,1567724632.323 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-09-05T23:03:52.328Z,1567724632.328 [DropWeight] Loaded
2019-09-05T23:03:52.328Z,1567724632.328 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-09-05T23:03:52.429Z,1567724632.429 [NAL9602] Loaded
2019-09-05T23:03:52.430Z,1567724632.430 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-09-05T23:03:52.446Z,1567724632.446 [Onboard] Loaded
2019-09-05T23:03:52.446Z,1567724632.446 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-09-05T23:03:52.450Z,1567724632.450 [Radio_Surface] Loaded
2019-09-05T23:03:52.450Z,1567724632.450 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-09-05T23:03:52.451Z,1567724632.451 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408EB4E0
2019-09-05T23:03:52.451Z,1567724632.451 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4741
2019-09-05T23:03:52.498Z,1567724632.498 [Rowe_600] Loaded
2019-09-05T23:03:52.498Z,1567724632.498 [ComponentRegistry](DEBUG): SyncComponent "Rowe_600" handled in the control thread.
2019-09-05T23:03:54.019Z,1567724634.019 [BPC1] Loaded
2019-09-05T23:03:54.020Z,1567724634.020 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-09-05T23:03:54.020Z,1567724634.020 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-09-05T23:03:54.021Z,1567724634.021 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-09-05T23:03:54.127Z,1567724634.127 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-09-05T23:03:54.127Z,1567724634.127 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-09-05T23:03:54.148Z,1567724634.148 [NavChart] Loaded
2019-09-05T23:03:54.149Z,1567724634.149 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-09-05T23:03:54.153Z,1567724634.153 [UniversalFixResidualReporter] Loaded
2019-09-05T23:03:54.153Z,1567724634.153 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-09-05T23:03:54.154Z,1567724634.154 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-09-05T23:03:54.154Z,1567724634.154 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-09-05T23:03:54.289Z,1567724634.289 [SBIT](DEBUG): Construct Startup Built In Test.
2019-09-05T23:03:54.301Z,1567724634.301 [SBIT] Loaded
2019-09-05T23:03:54.301Z,1567724634.301 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-09-05T23:03:54.302Z,1567724634.302 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-09-05T23:03:54.314Z,1567724634.314 [IBIT] Loaded
2019-09-05T23:03:54.314Z,1567724634.314 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-09-05T23:03:54.318Z,1567724634.318 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-09-05T23:03:54.461Z,1567724634.461 [CBIT] Loaded
2019-09-05T23:03:54.461Z,1567724634.461 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-09-05T23:03:54.462Z,1567724634.462 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-09-05T23:03:54.462Z,1567724634.462 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-09-05T23:03:54.566Z,1567724634.566 [BuoyancyServo] Loaded
2019-09-05T23:03:54.567Z,1567724634.567 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-09-05T23:03:54.578Z,1567724634.578 [ElevatorServo] Loaded
2019-09-05T23:03:54.579Z,1567724634.579 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-09-05T23:03:54.590Z,1567724634.590 [MassServo] Loaded
2019-09-05T23:03:54.590Z,1567724634.590 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-09-05T23:03:54.602Z,1567724634.602 [RudderServo] Loaded
2019-09-05T23:03:54.602Z,1567724634.602 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-09-05T23:03:54.614Z,1567724634.614 [ThrusterServo] Loaded
2019-09-05T23:03:54.614Z,1567724634.614 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-09-05T23:03:54.614Z,1567724634.614 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-09-05T23:03:54.615Z,1567724634.615 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-09-05T23:03:54.628Z,1567724634.628 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-09-05T23:03:54.629Z,1567724634.629 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-09-05T23:03:54.806Z,1567724634.806 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-09-05T23:03:54.812Z,1567724634.812 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-09-05T23:03:54.813Z,1567724634.813 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-09-05T23:03:54.818Z,1567724634.818 [CTD_Seabird](INFO): created writer for : depth
2019-09-05T23:03:54.819Z,1567724634.819 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-09-05T23:03:54.824Z,1567724634.824 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-09-05T23:03:54.824Z,1567724634.824 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-09-05T23:03:54.830Z,1567724634.830 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-09-05T23:03:54.830Z,1567724634.830 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-09-05T23:03:54.835Z,1567724634.835 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-09-05T23:03:54.836Z,1567724634.836 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-09-05T23:03:54.842Z,1567724634.842 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-09-05T23:03:54.842Z,1567724634.842 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-09-05T23:03:54.847Z,1567724634.847 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-09-05T23:03:54.876Z,1567724634.876 [CTD_Seabird] Loaded
2019-09-05T23:03:54.876Z,1567724634.876 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-09-05T23:03:54.877Z,1567724634.877 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A6F4E0
2019-09-05T23:03:54.877Z,1567724634.877 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4742
2019-09-05T23:03:54.928Z,1567724634.928 [WetLabsBB2FL] Loaded
2019-09-05T23:03:54.928Z,1567724634.928 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-09-05T23:03:54.929Z,1567724634.929 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A9F4E0
2019-09-05T23:03:54.929Z,1567724634.929 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4743
2019-09-05T23:03:54.930Z,1567724634.930 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-09-05T23:03:54.934Z,1567724634.934 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-09-05T23:03:54.935Z,1567724634.935 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-09-05T23:03:54.942Z,1567724634.942 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-09-05T23:03:54.943Z,1567724634.943 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACF4E0
2019-09-05T23:03:54.943Z,1567724634.943 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4744
2019-09-05T23:03:54.948Z,1567724634.948 [Supervisor](INFO): Main Thread ID is 4465
2019-09-05T23:03:54.948Z,1567724634.948 [Supervisor](DEBUG): Running supervisor.
2019-09-05T23:03:54.949Z,1567724634.949 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4745
2019-09-05T23:03:54.951Z,1567724634.951 [controlThread ThreadHandler](INFO): Handler Thread ID is 4746
2019-09-05T23:03:54.952Z,1567724634.952 [controlThread](DEBUG): Initializing ControlThread
2019-09-05T23:03:54.952Z,1567724634.952 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-09-05T23:03:54.954Z,1567724634.954 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-09-05T23:03:54.955Z,1567724634.955 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-09-05T23:03:54.955Z,1567724634.955 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-09-05T23:03:54.956Z,1567724634.956 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-09-05T23:03:54.956Z,1567724634.956 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-09-05T23:03:54.956Z,1567724634.956 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-09-05T23:03:54.957Z,1567724634.957 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-09-05T23:03:54.957Z,1567724634.957 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-09-05T23:03:54.958Z,1567724634.958 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-09-05T23:03:54.963Z,1567724634.963 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-09-05T23:03:54.964Z,1567724634.964 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-09-05T23:03:54.964Z,1567724634.964 [SBIT](INFO): Initialize SBIT Component.
2019-09-05T23:03:54.965Z,1567724634.965 [SBIT](IMPORTANT): git: 2019-08-21a
2019-09-05T23:03:54.965Z,1567724634.965 [SBIT](INFO): git hash: 91726968a91bb65c09438a13bfa0101a916cbc64
2019-09-05T23:03:54.965Z,1567724634.965 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-09-05T23:03:54.965Z,1567724634.965 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-09-05T23:03:54.966Z,1567724634.966 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2019-09-05T23:03:54.967Z,1567724634.967 [IBIT](INFO): Initialize IBIT Component.
2019-09-05T23:03:54.968Z,1567724634.968 [CBIT](DEBUG): Initialize CBIT Component.
2019-09-05T23:03:54.969Z,1567724634.969 [logger ThreadHandler](INFO): Handler Thread ID is 4747
2019-09-05T23:03:54.981Z,1567724634.981 [CBIT](DEBUG): Initialized mux pins.
2019-09-05T23:03:54.981Z,1567724634.981 [CBIT](DEBUG): Initializing the watchdog timer.
2019-09-05T23:03:54.989Z,1567724634.989 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4748
2019-09-05T23:03:54.990Z,1567724634.990 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-09-05T23:03:55.001Z,1567724635.001 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4749
2019-09-05T23:03:55.005Z,1567724635.005 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-09-05T23:03:55.005Z,1567724635.005 [CBIT](DEBUG): Initializing heartbeat.
2019-09-05T23:03:55.013Z,1567724635.013 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4750
2019-09-05T23:03:55.014Z,1567724635.014 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-09-05T23:03:55.017Z,1567724635.017 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-09-05T23:03:55.018Z,1567724635.018 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4752
2019-09-05T23:03:55.020Z,1567724635.020 [WetLabsBB2FL](INFO): Powering down
2019-09-05T23:03:55.049Z,1567724635.049 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4753
2019-09-05T23:03:55.052Z,1567724635.052 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-09-05T23:03:55.053Z,1567724635.053 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-09-05T23:03:55.054Z,1567724635.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-09-05T23:03:55.054Z,1567724635.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-09-05T23:03:55.054Z,1567724635.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-09-05T23:03:55.054Z,1567724635.054 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-09-05T23:03:55.054Z,1567724635.054 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-09-05T23:03:55.055Z,1567724635.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-09-05T23:03:55.055Z,1567724635.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-09-05T23:03:55.055Z,1567724635.055 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-09-05T23:03:55.055Z,1567724635.055 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-09-05T23:03:55.077Z,1567724635.077 [CBIT](DEBUG): Deactivating GF circuits.
2019-09-05T23:03:55.077Z,1567724635.077 [CBIT](DEBUG): Deactivating emergency mode.
2019-09-05T23:03:55.113Z,1567724635.113 [CBIT](DEBUG): Backplane powered.
2019-09-05T23:03:55.115Z,1567724635.115 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-09-05T23:03:55.124Z,1567724635.124 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-05T23:03:55.135Z,1567724635.135 [MissionManager](DEBUG):
2019-09-05T23:03:55.136Z,1567724635.136 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-09-05T23:03:55.204Z,1567724635.204 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-09-05T23:03:55.221Z,1567724635.221 [Default:A.Wait](DEBUG): Construct Wait.
2019-09-05T23:03:55.223Z,1567724635.223 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-09-05T23:03:55.241Z,1567724635.241 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-09-05T23:03:55.244Z,1567724635.244 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-09-05T23:03:55.289Z,1567724635.289 [Default:E.Execute](DEBUG): Construct Execute.
2019-09-05T23:03:55.302Z,1567724635.302 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2019-09-05T23:03:55.320Z,1567724635.320 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,Rowe_600,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-09-05T23:03:55.333Z,1567724635.333 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-09-05T23:03:55.364Z,1567724635.364 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar
2019-09-05T23:03:55.405Z,1567724635.405 [Radio_Surface](INFO): Powering up
2019-09-05T23:03:55.434Z,1567724635.434 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-09-05T23:03:55.444Z,1567724635.444 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-09-05T23:03:55.453Z,1567724635.453 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-09-05T23:03:55.454Z,1567724635.454 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-09-05T23:03:55.461Z,1567724635.461 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-09-05T23:03:55.462Z,1567724635.462 [MassServo](DEBUG): Initializing EZServoServo.
2019-09-05T23:03:55.469Z,1567724635.469 [MassServo](DEBUG): Initializing MassServo.
2019-09-05T23:03:55.470Z,1567724635.470 [RudderServo](DEBUG): Initializing EZServoServo.
2019-09-05T23:03:55.477Z,1567724635.477 [RudderServo](DEBUG): Initializing RudderServo.
2019-09-05T23:03:55.478Z,1567724635.478 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-09-05T23:03:55.489Z,1567724635.489 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-09-05T23:04:17.214Z,1567724657.214 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam1Range
2019-09-05T23:04:18.494Z,1567724658.494 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:19.290Z,1567724659.290 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:20.506Z,1567724660.506 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:21.322Z,1567724661.322 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:22.434Z,1567724662.434 [NAL9602](INFO): Powering up NAL9602
2019-09-05T23:04:22.538Z,1567724662.538 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:23.366Z,1567724663.366 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:27.752Z,1567724667.752 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:28.909Z,1567724668.909 [Rowe_600](ERROR): Failed to parse:
:BE,-32768,-32768,-32768,V
2019-09-05T23:04:30.161Z,1567724670.161 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:30.970Z,1567724670.970 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:32.221Z,1567724672.221 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:33.009Z,1567724673.009 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:33.338Z,1567724673.338 [NAL9602](INFO): NAL9602 initialized
2019-09-05T23:04:34.161Z,1567724674.161 [NAL9602](DEBUG): Fix Requested
2019-09-05T23:04:34.226Z,1567724674.226 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:35.077Z,1567724675.077 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:36.242Z,1567724676.242 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:37.038Z,1567724677.038 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:37.919Z,1567724677.919 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:39.063Z,1567724679.063 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:40.270Z,1567724680.270 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:41.089Z,1567724681.089 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:44.310Z,1567724684.310 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:45.553Z,1567724685.553 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:04:46.034Z,1567724686.034 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam2Range
2019-09-05T23:04:46.035Z,1567724686.035 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam3Range
2019-09-05T23:04:46.036Z,1567724686.036 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam4Range
2019-09-05T23:04:46.327Z,1567724686.327 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:46.327Z,1567724686.327 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:46.328Z,1567724686.328 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:46.333Z,1567724686.333 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:04:47.547Z,1567724687.547 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:47.548Z,1567724687.548 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:47.553Z,1567724687.553 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:47.553Z,1567724687.553 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:04:48.360Z,1567724688.360 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:48.360Z,1567724688.360 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:48.361Z,1567724688.361 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:48.361Z,1567724688.361 [Reporter](INFO): Rowe_600.Beam4Range 0.986000 m
2019-09-05T23:04:49.557Z,1567724689.557 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:49.558Z,1567724689.558 [Reporter](INFO): Rowe_600.Beam2Range 1.273000 m
2019-09-05T23:04:49.559Z,1567724689.559 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:49.559Z,1567724689.559 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:04:52.776Z,1567724692.776 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:52.776Z,1567724692.776 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:04:52.777Z,1567724692.777 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:52.778Z,1567724692.778 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:04:53.996Z,1567724693.996 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:54.001Z,1567724694.001 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:04:54.002Z,1567724694.002 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:54.002Z,1567724694.002 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:04:54.386Z,1567724694.386 [SBIT](IMPORTANT): Beginning Startup BIT
2019-09-05T23:04:54.390Z,1567724694.390 [CBIT](IMPORTANT): Beginning ground fault scan
2019-09-05T23:04:54.870Z,1567724694.870 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:54.870Z,1567724694.870 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:54.871Z,1567724694.871 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:54.871Z,1567724694.871 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:04:56.262Z,1567724696.262 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:56.262Z,1567724696.262 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:56.263Z,1567724696.263 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:56.263Z,1567724696.263 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:04:57.038Z,1567724697.038 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:57.039Z,1567724697.039 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:57.039Z,1567724697.039 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:57.040Z,1567724697.040 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:04:58.322Z,1567724698.322 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:04:58.323Z,1567724698.323 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:04:58.323Z,1567724698.323 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:04:58.324Z,1567724698.324 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:05:05.447Z,1567724705.447 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.019873
CHAN A1 (24V): 0.166970
CHAN A2 (12V): -0.013073
CHAN A3 (5V): -0.002589
CHAN B0 (3.3V): -0.000507
CHAN B1 (3.15aV): 0.000125
CHAN B2 (3.15bV): -0.000470
CHAN B3 (GND): 0.001997
OPEN: 0.006989
Full Scale Calc: 4.765 mA, -1.589 mA
2019-09-05T23:05:08.220Z,1567724708.220 [Rowe_600](ERROR): No DVL communication! Re-initializing
2019-09-05T23:05:08.220Z,1567724708.220 [Rowe_600] Communications Fault, FailCount= 1
2019-09-05T23:05:08.220Z,1567724708.220 [Rowe_600](ERROR): Communications Fault
2019-09-05T23:05:08.236Z,1567724708.236 [CBIT](ERROR): Communications Fault in component: Rowe_600
2019-09-05T23:05:08.729Z,1567724708.729 [Rowe_600](INFO): Powering down
2019-09-05T23:05:09.558Z,1567724709.558 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-09-05T23:05:09.558Z,1567724709.558 [Rowe_600] No Fault, FailCount= 1
2019-09-05T23:05:12.030Z,1567724712.030 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:12.031Z,1567724712.031 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:12.031Z,1567724712.031 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:12.032Z,1567724712.032 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:13.246Z,1567724713.246 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:13.247Z,1567724713.247 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:13.247Z,1567724713.247 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:13.248Z,1567724713.248 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:14.110Z,1567724714.110 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:14.111Z,1567724714.111 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:14.111Z,1567724714.111 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:14.112Z,1567724714.112 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:15.054Z,1567724715.054 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:15.054Z,1567724715.054 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:15.055Z,1567724715.055 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:15.056Z,1567724715.056 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:05:16.097Z,1567724716.097 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:16.099Z,1567724716.099 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:16.099Z,1567724716.099 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:16.100Z,1567724716.100 [Reporter](INFO): Rowe_600.Beam4Range 0.991000 m
2019-09-05T23:05:17.310Z,1567724717.310 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:17.311Z,1567724717.311 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:17.311Z,1567724717.311 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:17.312Z,1567724717.312 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:20.534Z,1567724720.534 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:20.534Z,1567724720.534 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:20.535Z,1567724720.535 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:20.536Z,1567724720.536 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:05:21.350Z,1567724721.350 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:05:21.351Z,1567724721.351 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:05:21.351Z,1567724721.351 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:21.352Z,1567724721.352 [Reporter](INFO): Rowe_600.Beam4Range 0.039000 m
2019-09-05T23:05:22.550Z,1567724722.550 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:05:22.551Z,1567724722.551 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:05:22.551Z,1567724722.551 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:22.552Z,1567724722.552 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:23.362Z,1567724723.362 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:23.363Z,1567724723.363 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:05:23.363Z,1567724723.363 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:23.364Z,1567724723.364 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:24.570Z,1567724724.570 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:05:24.570Z,1567724724.570 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:24.571Z,1567724724.571 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:24.571Z,1567724724.571 [Reporter](INFO): Rowe_600.Beam4Range 0.042000 m
2019-09-05T23:05:25.390Z,1567724725.390 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:25.391Z,1567724725.391 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:25.391Z,1567724725.391 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:25.392Z,1567724725.392 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:26.738Z,1567724726.738 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:26.738Z,1567724726.738 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:26.739Z,1567724726.739 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:26.740Z,1567724726.740 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:27.378Z,1567724727.378 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:27.378Z,1567724727.378 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:27.379Z,1567724727.379 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:27.379Z,1567724727.379 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:28.606Z,1567724728.606 [Reporter](INFO): Rowe_600.Beam1Range 0.966000 m
2019-09-05T23:05:28.607Z,1567724728.607 [Reporter](INFO): Rowe_600.Beam2Range 1.068000 m
2019-09-05T23:05:28.607Z,1567724728.607 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:28.608Z,1567724728.608 [Reporter](INFO): Rowe_600.Beam4Range 0.965000 m
2019-09-05T23:05:29.374Z,1567724729.374 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:29.375Z,1567724729.375 [Reporter](INFO): Rowe_600.Beam2Range 1.068000 m
2019-09-05T23:05:29.375Z,1567724729.375 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:29.376Z,1567724729.376 [Reporter](INFO): Rowe_600.Beam4Range 0.966000 m
2019-09-05T23:05:30.590Z,1567724730.590 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:30.590Z,1567724730.590 [Reporter](INFO): Rowe_600.Beam2Range 1.069000 m
2019-09-05T23:05:30.591Z,1567724730.591 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:30.591Z,1567724730.591 [Reporter](INFO): Rowe_600.Beam4Range 0.974000 m
2019-09-05T23:05:31.402Z,1567724731.402 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:31.403Z,1567724731.403 [Reporter](INFO): Rowe_600.Beam2Range 1.069000 m
2019-09-05T23:05:31.403Z,1567724731.403 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:31.404Z,1567724731.404 [Reporter](INFO): Rowe_600.Beam4Range 0.974000 m
2019-09-05T23:05:32.606Z,1567724732.606 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:32.607Z,1567724732.607 [Reporter](INFO): Rowe_600.Beam2Range 1.069000 m
2019-09-05T23:05:32.607Z,1567724732.607 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:32.608Z,1567724732.608 [Reporter](INFO): Rowe_600.Beam4Range 0.974000 m
2019-09-05T23:05:33.426Z,1567724733.426 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:33.427Z,1567724733.427 [Reporter](INFO): Rowe_600.Beam2Range 1.276000 m
2019-09-05T23:05:33.427Z,1567724733.427 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:33.428Z,1567724733.428 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:37.042Z,1567724737.042 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:37.042Z,1567724737.042 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:37.043Z,1567724737.043 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:37.044Z,1567724737.044 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:38.038Z,1567724738.038 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:38.039Z,1567724738.039 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:38.039Z,1567724738.039 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:38.040Z,1567724738.040 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:38.755Z,1567724738.755 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:05:38.850Z,1567724738.850 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:38.851Z,1567724738.851 [Reporter](INFO): Rowe_600.Beam2Range 1.066000 m
2019-09-05T23:05:38.852Z,1567724738.852 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:38.852Z,1567724738.852 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:39.758Z,1567724739.758 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:39.759Z,1567724739.759 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:39.759Z,1567724739.759 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:39.760Z,1567724739.760 [Reporter](INFO): Rowe_600.Beam4Range 0.974000 m
2019-09-05T23:05:40.798Z,1567724740.798 [Reporter](INFO): Rowe_600.Beam1Range 0.958000 m
2019-09-05T23:05:40.799Z,1567724740.799 [Reporter](INFO): Rowe_600.Beam2Range 1.064000 m
2019-09-05T23:05:40.799Z,1567724740.799 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:40.800Z,1567724740.800 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:42.007Z,1567724742.007 [Reporter](INFO): Rowe_600.Beam1Range 1.268000 m
2019-09-05T23:05:42.007Z,1567724742.007 [Reporter](INFO): Rowe_600.Beam2Range 1.276000 m
2019-09-05T23:05:42.008Z,1567724742.008 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:42.008Z,1567724742.008 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:45.590Z,1567724745.590 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:05:45.591Z,1567724745.591 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:45.591Z,1567724745.591 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:45.592Z,1567724745.592 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:05:45.995Z,1567724745.995 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:45.995Z,1567724745.995 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:45.996Z,1567724745.996 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:46.001Z,1567724746.001 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:47.194Z,1567724747.194 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:47.194Z,1567724747.194 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:47.195Z,1567724747.195 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:47.196Z,1567724747.196 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:05:48.002Z,1567724748.002 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:48.003Z,1567724748.003 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:48.003Z,1567724748.003 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:48.004Z,1567724748.004 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:05:48.407Z,1567724748.407 [SBIT](IMPORTANT): SBIT PASSED
2019-09-05T23:05:48.438Z,1567724748.438 [CommandLine](IMPORTANT): got command configSet list
2019-09-05T23:05:48.438Z,1567724748.438 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-09-05T23:05:48.439Z,1567724748.439 [CommandLine](IMPORTANT): Micromodem.loadAtStartup=0 bool;
2019-09-05T23:05:48.439Z,1567724748.439 [CommandLine](IMPORTANT): VerticalControl.massDefault=10 millimeter;
2019-09-05T23:05:48.815Z,1567724748.815 [MissionManager](IMPORTANT): Started mission Startup
2019-09-05T23:05:48.815Z,1567724748.815 [Startup] Running Loop=1
2019-09-05T23:05:48.815Z,1567724748.815 [Startup](DEBUG): Aggregate::initialize Startup
2019-09-05T23:05:48.815Z,1567724748.815 [Startup:A.GoToSurface] Running Loop=1
2019-09-05T23:05:48.815Z,1567724748.815 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-05T23:05:48.816Z,1567724748.816 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-05T23:05:48.817Z,1567724748.817 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-05T23:05:48.817Z,1567724748.817 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-05T23:05:48.818Z,1567724748.818 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-05T23:05:48.818Z,1567724748.818 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-05T23:05:48.819Z,1567724748.819 [Startup:StartupSatComms] Running Loop=1
2019-09-05T23:05:48.820Z,1567724748.820 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-09-05T23:05:48.820Z,1567724748.820 [Startup:StartupSatComms:A] Running Loop=1
2019-09-05T23:05:49.230Z,1567724749.230 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-09-05T23:05:49.244Z,1567724749.244 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:49.244Z,1567724749.244 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:49.249Z,1567724749.249 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:49.250Z,1567724749.250 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:05:50.033Z,1567724750.033 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:50.034Z,1567724750.034 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:50.034Z,1567724750.034 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:50.035Z,1567724750.035 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:05:50.862Z,1567724750.862 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:05:53.311Z,1567724753.311 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:53.311Z,1567724753.311 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:53.312Z,1567724753.312 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:53.313Z,1567724753.313 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:54.470Z,1567724754.470 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:54.471Z,1567724754.471 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:54.471Z,1567724754.471 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:54.472Z,1567724754.472 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:05:55.248Z,1567724755.248 [NAL9602](INFO): SBD MO Status=2, MOMSN=642, MT Status=2, MTMSN=0
2019-09-05T23:05:55.248Z,1567724755.248 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-09-05T23:05:55.288Z,1567724755.288 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:55.288Z,1567724755.288 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:55.289Z,1567724755.289 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:55.290Z,1567724755.290 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:56.522Z,1567724756.522 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:56.523Z,1567724756.523 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:56.523Z,1567724756.523 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:56.524Z,1567724756.524 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:57.335Z,1567724757.335 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:57.335Z,1567724757.335 [Reporter](INFO): Rowe_600.Beam2Range 1.058000 m
2019-09-05T23:05:57.336Z,1567724757.336 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:57.341Z,1567724757.341 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:05:58.520Z,1567724758.520 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:05:58.520Z,1567724758.520 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:05:58.526Z,1567724758.526 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:05:58.526Z,1567724758.526 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:01.768Z,1567724761.768 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:06:01.783Z,1567724761.783 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:01.784Z,1567724761.784 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:01.784Z,1567724761.784 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:01.785Z,1567724761.785 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:06:02.987Z,1567724762.987 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:02.988Z,1567724762.988 [Reporter](INFO): Rowe_600.Beam2Range 0.035000 m
2019-09-05T23:06:02.988Z,1567724762.988 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:02.993Z,1567724762.993 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:03.797Z,1567724763.797 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:03.797Z,1567724763.797 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:03.798Z,1567724763.798 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:03.798Z,1567724763.798 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:04.980Z,1567724764.980 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:04.980Z,1567724764.980 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:04.981Z,1567724764.981 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:04.981Z,1567724764.981 [Reporter](INFO): Rowe_600.Beam4Range 0.991000 m
2019-09-05T23:06:05.803Z,1567724765.803 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:05.803Z,1567724765.803 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:05.804Z,1567724765.804 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:05.805Z,1567724765.805 [Reporter](INFO): Rowe_600.Beam4Range 0.989000 m
2019-09-05T23:06:07.030Z,1567724767.030 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:07.030Z,1567724767.030 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:07.031Z,1567724767.031 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:07.031Z,1567724767.031 [Reporter](INFO): Rowe_600.Beam4Range 1.273000 m
2019-09-05T23:06:17.079Z,1567724777.079 [Rowe_600](ERROR): No DVL communication! Re-initializing
2019-09-05T23:06:17.079Z,1567724777.079 [Rowe_600] Communications Fault, FailCount= 1
2019-09-05T23:06:17.079Z,1567724777.079 [Rowe_600](ERROR): Communications Fault
2019-09-05T23:06:17.242Z,1567724777.242 [CBIT](ERROR): Communications Fault in component: Rowe_600
2019-09-05T23:06:17.585Z,1567724777.585 [Rowe_600](INFO): Powering down
2019-09-05T23:06:18.187Z,1567724778.187 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam2Range
2019-09-05T23:06:18.187Z,1567724778.187 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam3Range
2019-09-05T23:06:18.187Z,1567724778.187 [CommandLine](IMPORTANT): got command report touch Rowe_600.Beam4Range
2019-09-05T23:06:18.350Z,1567724778.350 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-09-05T23:06:18.350Z,1567724778.350 [Rowe_600] No Fault, FailCount= 1
2019-09-05T23:06:21.059Z,1567724781.059 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004935
2019-09-05T23:06:21.130Z,1567724781.130 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:21.130Z,1567724781.130 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:21.131Z,1567724781.131 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:21.132Z,1567724781.132 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:21.942Z,1567724781.942 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:21.943Z,1567724781.943 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:06:21.943Z,1567724781.943 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:21.944Z,1567724781.944 [Reporter](INFO): Rowe_600.Beam4Range 0.039000 m
2019-09-05T23:06:22.755Z,1567724782.755 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:22.756Z,1567724782.756 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:22.757Z,1567724782.757 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:22.757Z,1567724782.757 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:23.983Z,1567724783.983 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:23.983Z,1567724783.983 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:23.984Z,1567724783.984 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:23.989Z,1567724783.989 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:24.770Z,1567724784.770 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:24.771Z,1567724784.771 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:24.771Z,1567724784.771 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:24.772Z,1567724784.772 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:25.989Z,1567724785.989 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:25.989Z,1567724785.989 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:25.990Z,1567724785.990 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:25.991Z,1567724785.991 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:06:26.833Z,1567724786.833 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:06:29.224Z,1567724789.224 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:29.225Z,1567724789.225 [Reporter](INFO): Rowe_600.Beam2Range 0.028000 m
2019-09-05T23:06:29.225Z,1567724789.225 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:29.226Z,1567724789.226 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:06:30.067Z,1567724790.067 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:30.068Z,1567724790.068 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:30.068Z,1567724790.068 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:30.069Z,1567724790.069 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:31.234Z,1567724791.234 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:31.234Z,1567724791.234 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:31.235Z,1567724791.235 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:31.236Z,1567724791.236 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:32.063Z,1567724792.063 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:32.063Z,1567724792.063 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:32.064Z,1567724792.064 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:32.065Z,1567724792.065 [Reporter](INFO): Rowe_600.Beam4Range 0.969000 m
2019-09-05T23:06:33.259Z,1567724793.259 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:33.260Z,1567724793.260 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:33.260Z,1567724793.260 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:33.261Z,1567724793.261 [Reporter](INFO): Rowe_600.Beam4Range 0.982000 m
2019-09-05T23:06:34.497Z,1567724794.497 [Reporter](INFO): Rowe_600.Beam1Range 1.273000 m
2019-09-05T23:06:34.498Z,1567724794.498 [Reporter](INFO): Rowe_600.Beam2Range 1.273000 m
2019-09-05T23:06:34.498Z,1567724794.498 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:34.499Z,1567724794.499 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:06:35.270Z,1567724795.270 [Reporter](INFO): Rowe_600.Beam1Range 0.965000 m
2019-09-05T23:06:35.270Z,1567724795.270 [Reporter](INFO): Rowe_600.Beam2Range 1.066000 m
2019-09-05T23:06:35.271Z,1567724795.271 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:35.271Z,1567724795.271 [Reporter](INFO): Rowe_600.Beam4Range 0.982000 m
2019-09-05T23:06:36.107Z,1567724796.107 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:36.107Z,1567724796.107 [Reporter](INFO): Rowe_600.Beam2Range 1.058000 m
2019-09-05T23:06:36.108Z,1567724796.108 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:36.109Z,1567724796.109 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:06:37.310Z,1567724797.310 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:37.311Z,1567724797.311 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:37.311Z,1567724797.311 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:37.312Z,1567724797.312 [Reporter](INFO): Rowe_600.Beam4Range 0.978000 m
2019-09-05T23:06:38.108Z,1567724798.108 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:38.108Z,1567724798.108 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:38.109Z,1567724798.109 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:38.110Z,1567724798.110 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:06:39.318Z,1567724799.318 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:39.319Z,1567724799.319 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:39.320Z,1567724799.320 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:39.320Z,1567724799.320 [Reporter](INFO): Rowe_600.Beam4Range 0.988000 m
2019-09-05T23:06:39.754Z,1567724799.754 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:06:40.558Z,1567724800.558 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:40.559Z,1567724800.559 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:40.560Z,1567724800.560 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:40.560Z,1567724800.560 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:06:43.885Z,1567724803.885 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:43.886Z,1567724803.886 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:43.887Z,1567724803.887 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:43.887Z,1567724803.887 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:06:44.960Z,1567724804.960 [Rowe_600](ERROR): Failed to parse:
:TS,19090516044635,35.0,+15.0, 0.0,1500.0, C
2019-09-05T23:06:46.203Z,1567724806.203 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:46.203Z,1567724806.203 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:46.204Z,1567724806.204 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:46.205Z,1567724806.205 [Reporter](INFO): Rowe_600.Beam4Range 0.992000 m
2019-09-05T23:06:47.012Z,1567724807.012 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:47.012Z,1567724807.012 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:47.013Z,1567724807.013 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:47.014Z,1567724807.014 [Reporter](INFO): Rowe_600.Beam4Range 0.991000 m
2019-09-05T23:06:48.202Z,1567724808.202 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:48.203Z,1567724808.203 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:48.204Z,1567724808.204 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:48.204Z,1567724808.204 [Reporter](INFO): Rowe_600.Beam4Range 1.273000 m
2019-09-05T23:06:48.998Z,1567724808.998 [Startup:StartupSatComms:A](INFO): Timed out from 2019-09-05T23:05:48.8Z
2019-09-05T23:06:48.998Z,1567724808.998 [Startup:StartupSatComms:A] Stopped
2019-09-05T23:06:48.998Z,1567724808.998 [Startup:StartupSatComms:B] Running Loop=1
2019-09-05T23:06:49.398Z,1567724809.398 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-09-05T23:06:51.490Z,1567724811.490 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:51.491Z,1567724811.491 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:06:51.491Z,1567724811.491 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:51.492Z,1567724811.492 [Reporter](INFO): Rowe_600.Beam4Range 0.039000 m
2019-09-05T23:06:52.306Z,1567724812.306 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:52.307Z,1567724812.307 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:06:52.307Z,1567724812.307 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:52.308Z,1567724812.308 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:53.495Z,1567724813.495 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:53.496Z,1567724813.496 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:06:53.496Z,1567724813.496 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:53.497Z,1567724813.497 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:54.315Z,1567724814.315 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:54.315Z,1567724814.315 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:06:54.316Z,1567724814.316 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:54.321Z,1567724814.321 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:55.089Z,1567724815.089 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-09-05T23:06:55.090Z,1567724815.090 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-09-05T23:06:55.133Z,1567724815.133 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-09-05T23:06:55.518Z,1567724815.518 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-09-05T23:06:55.518Z,1567724815.518 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-09-05T23:06:55.519Z,1567724815.519 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:06:55.520Z,1567724815.520 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:55.520Z,1567724815.520 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:55.529Z,1567724815.529 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:56.301Z,1567724816.301 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:56.301Z,1567724816.301 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:56.302Z,1567724816.302 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:56.303Z,1567724816.303 [Reporter](INFO): Rowe_600.Beam4Range 1.000000 m
2019-09-05T23:06:56.600Z,1567724816.600 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190905T225033/Courier0010.lzma
2019-09-05T23:06:57.406Z,1567724817.406 [DataOverHttps](INFO): Moved sent file to Logs/20190905T225033/Courier0010.lzma.bak
2019-09-05T23:06:57.406Z,1567724817.406 [DataOverHttps](INFO): SBD MOMSN=11744846
2019-09-05T23:06:57.540Z,1567724817.540 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:57.545Z,1567724817.545 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:57.545Z,1567724817.545 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:57.546Z,1567724817.546 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:58.345Z,1567724818.345 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:58.347Z,1567724818.347 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:58.350Z,1567724818.350 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:58.352Z,1567724818.352 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:06:59.571Z,1567724819.571 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:06:59.571Z,1567724819.571 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:06:59.572Z,1567724819.572 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:06:59.573Z,1567724819.573 [Reporter](INFO): Rowe_600.Beam4Range 1.273000 m
2019-09-05T23:07:02.766Z,1567724822.766 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:02.767Z,1567724822.767 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:07:02.768Z,1567724822.768 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:02.768Z,1567724822.768 [Reporter](INFO): Rowe_600.Beam4Range 0.039000 m
2019-09-05T23:07:03.987Z,1567724823.987 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:03.987Z,1567724823.987 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:03.988Z,1567724823.988 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:03.993Z,1567724823.993 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:04.794Z,1567724824.794 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:04.794Z,1567724824.794 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:07:04.795Z,1567724824.795 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:04.795Z,1567724824.795 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:06.020Z,1567724826.020 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:06.020Z,1567724826.020 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:06.025Z,1567724826.025 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:06.026Z,1567724826.026 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:06.871Z,1567724826.871 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:06.871Z,1567724826.871 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:06.872Z,1567724826.872 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:06.873Z,1567724826.873 [Reporter](INFO): Rowe_600.Beam4Range 0.041000 m
2019-09-05T23:07:07.695Z,1567724827.695 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:07.696Z,1567724827.696 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:07.696Z,1567724827.696 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:07.701Z,1567724827.701 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:08.860Z,1567724828.860 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:08.860Z,1567724828.860 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:08.865Z,1567724828.865 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:08.866Z,1567724828.866 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:10.040Z,1567724830.040 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:10.045Z,1567724830.045 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:10.046Z,1567724830.046 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:10.046Z,1567724830.046 [Reporter](INFO): Rowe_600.Beam4Range 0.985000 m
2019-09-05T23:07:10.865Z,1567724830.865 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:10.866Z,1567724830.866 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:10.866Z,1567724830.866 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:10.867Z,1567724830.867 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:12.388Z,1567724832.388 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190905T225033/Express0011.lzma
2019-09-05T23:07:13.194Z,1567724833.194 [DataOverHttps](INFO): Moved sent file to Logs/20190905T225033/Express0011.lzma.bak
2019-09-05T23:07:13.194Z,1567724833.194 [DataOverHttps](INFO): SBD MOMSN=11744848
2019-09-05T23:07:14.926Z,1567724834.926 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:14.927Z,1567724834.927 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:14.929Z,1567724834.929 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:14.933Z,1567724834.933 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:16.118Z,1567724836.118 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:16.119Z,1567724836.119 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:16.119Z,1567724836.119 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:16.120Z,1567724836.120 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:16.910Z,1567724836.910 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:16.910Z,1567724836.910 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:16.911Z,1567724836.911 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:16.911Z,1567724836.911 [Reporter](INFO): Rowe_600.Beam4Range 0.982000 m
2019-09-05T23:07:18.135Z,1567724838.135 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:18.136Z,1567724838.136 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:18.136Z,1567724838.136 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:18.141Z,1567724838.141 [Reporter](INFO): Rowe_600.Beam4Range 0.980000 m
2019-09-05T23:07:19.391Z,1567724839.391 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:19.392Z,1567724839.392 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:19.392Z,1567724839.392 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:19.401Z,1567724839.401 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:07:28.798Z,1567724848.798 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20190905T230344/Express0001.lzma
2019-09-05T23:07:29.385Z,1567724849.385 [Rowe_600](ERROR): No DVL communication! Re-initializing
2019-09-05T23:07:29.385Z,1567724849.385 [Rowe_600] Communications Fault, FailCount= 1
2019-09-05T23:07:29.385Z,1567724849.385 [Rowe_600](ERROR): Communications Fault
2019-09-05T23:07:29.400Z,1567724849.400 [CBIT](ERROR): Communications Fault in component: Rowe_600
2019-09-05T23:07:29.602Z,1567724849.602 [DataOverHttps](INFO): Moved sent file to Logs/20190905T230344/Express0001.lzma.bak
2019-09-05T23:07:29.602Z,1567724849.602 [DataOverHttps](INFO): SBD MOMSN=11744852
2019-09-05T23:07:29.909Z,1567724849.909 [Rowe_600](INFO): Powering down
2019-09-05T23:07:30.622Z,1567724850.622 [CBIT](INFO): Clearing failed state for component Rowe_600
2019-09-05T23:07:30.622Z,1567724850.622 [Rowe_600] No Fault, FailCount= 1
2019-09-05T23:07:33.438Z,1567724853.438 [Startup:StartupSatComms:B] Stopped
2019-09-05T23:07:33.439Z,1567724853.439 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-09-05T23:07:33.439Z,1567724853.439 [Startup:StartupSatComms] Stopped
2019-09-05T23:07:33.439Z,1567724853.439 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-09-05T23:07:33.440Z,1567724853.440 [Startup](INFO): Completed Startup
2019-09-05T23:07:33.440Z,1567724853.440 [MissionManager](INFO): Startup is completed.
2019-09-05T23:07:33.440Z,1567724853.440 [MissionManager](INFO): Uninitializing Mission Startup
2019-09-05T23:07:33.440Z,1567724853.440 [Startup] Stopped
2019-09-05T23:07:33.440Z,1567724853.440 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-09-05T23:07:33.441Z,1567724853.441 [Startup:A.GoToSurface] Stopped
2019-09-05T23:07:33.441Z,1567724853.441 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-05T23:07:34.052Z,1567724854.052 [MissionManager](IMPORTANT): Started mission Default
2019-09-05T23:07:34.052Z,1567724854.052 [Default] Running Loop=1
2019-09-05T23:07:34.057Z,1567724854.057 [Default](DEBUG): Aggregate::initialize Default
2019-09-05T23:07:34.057Z,1567724854.057 [Default:B.GoToSurface] Running Loop=1
2019-09-05T23:07:34.057Z,1567724854.057 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-09-05T23:07:34.057Z,1567724854.057 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-09-05T23:07:34.057Z,1567724854.057 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-09-05T23:07:34.058Z,1567724854.058 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-09-05T23:07:34.058Z,1567724854.058 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-09-05T23:07:34.058Z,1567724854.058 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-09-05T23:07:34.059Z,1567724854.059 [Default:A.Wait] Running Loop=1
2019-09-05T23:07:34.059Z,1567724854.059 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-09-05T23:07:34.081Z,1567724854.081 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:34.082Z,1567724854.082 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:34.082Z,1567724854.082 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:34.083Z,1567724854.083 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:07:35.060Z,1567724855.060 [Rowe_600](ERROR): Failed to parse:
:TS,19090516053635,35.0,+15.0, 0.0,1500.0, C
2019-09-05T23:07:35.935Z,1567724855.935 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:35.936Z,1567724855.936 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:35.936Z,1567724855.936 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:35.945Z,1567724855.945 [Reporter](INFO): Rowe_600.Beam4Range 0.992000 m
2019-09-05T23:07:37.115Z,1567724857.115 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:37.116Z,1567724857.116 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:37.116Z,1567724857.116 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:37.137Z,1567724857.137 [Reporter](INFO): Rowe_600.Beam4Range 0.992000 m
2019-09-05T23:07:38.322Z,1567724858.322 [Reporter](INFO): Rowe_600.Beam1Range 1.273000 m
2019-09-05T23:07:38.323Z,1567724858.323 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:38.324Z,1567724858.324 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:38.324Z,1567724858.324 [Reporter](INFO): Rowe_600.Beam4Range 1.271000 m
2019-09-05T23:07:41.543Z,1567724861.543 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:41.544Z,1567724861.544 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:41.544Z,1567724861.544 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:41.545Z,1567724861.545 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:42.358Z,1567724862.358 [Reporter](INFO): Rowe_600.Beam1Range 0.034000 m
2019-09-05T23:07:42.359Z,1567724862.359 [Reporter](INFO): Rowe_600.Beam2Range 0.037000 m
2019-09-05T23:07:42.360Z,1567724862.360 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:42.360Z,1567724862.360 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:43.550Z,1567724863.550 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:43.551Z,1567724863.551 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:43.552Z,1567724863.552 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:43.552Z,1567724863.552 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:44.390Z,1567724864.390 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:44.390Z,1567724864.390 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:44.391Z,1567724864.391 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:44.391Z,1567724864.391 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:45.597Z,1567724865.597 [Reporter](INFO): Rowe_600.Beam1Range 0.951000 m
2019-09-05T23:07:45.598Z,1567724865.598 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:45.598Z,1567724865.598 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:45.599Z,1567724865.599 [Reporter](INFO): Rowe_600.Beam4Range 0.989000 m
2019-09-05T23:07:46.435Z,1567724866.435 [Reporter](INFO): Rowe_600.Beam1Range 1.273000 m
2019-09-05T23:07:46.435Z,1567724866.435 [Reporter](INFO): Rowe_600.Beam2Range 0.000000 m
2019-09-05T23:07:46.436Z,1567724866.436 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:46.437Z,1567724866.437 [Reporter](INFO): Rowe_600.Beam4Range 0.000000 m
2019-09-05T23:07:47.276Z,1567724867.276 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-09-05T23:07:47.286Z,1567724867.286 [Default:A.Wait](INFO): Done Waiting.
2019-09-05T23:07:47.286Z,1567724867.286 [Default:A.Wait] Stopped
2019-09-05T23:07:47.286Z,1567724867.286 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-09-05T23:07:47.606Z,1567724867.606 [Default:CheckIn] Running Loop=1
2019-09-05T23:07:47.606Z,1567724867.606 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-09-05T23:07:47.606Z,1567724867.606 [Default:CheckIn:Read_GPS] Running Loop=1
2019-09-05T23:07:48.011Z,1567724868.011 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-09-05T23:07:48.186Z,1567724868.186 [CommandLine](IMPORTANT): got command restart application
2019-09-05T23:07:49.193Z,1567724869.193 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-09-05T23:07:49.193Z,1567724869.193 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:49.194Z,1567724869.194 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.209Z,1567724869.209 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2019-09-05T23:07:49.209Z,1567724869.209 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.210Z,1567724869.210 [CommandLine](INFO): Join timeout helper Thread ID is 4777
2019-09-05T23:07:49.210Z,1567724869.210 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-09-05T23:07:49.210Z,1567724869.210 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.211Z,1567724869.211 [NavChartDb](INFO): Join timeout helper Thread ID is 4778
2019-09-05T23:07:49.217Z,1567724869.217 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:49.217Z,1567724869.217 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.229Z,1567724869.229 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-09-05T23:07:49.229Z,1567724869.229 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.229Z,1567724869.229 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4779
2019-09-05T23:07:49.445Z,1567724869.445 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:49.445Z,1567724869.445 [WetLabsBB2FL](INFO): Powering down
2019-09-05T23:07:49.446Z,1567724869.446 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.465Z,1567724869.465 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-09-05T23:07:49.465Z,1567724869.465 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:49.465Z,1567724869.465 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4780
2019-09-05T23:07:49.691Z,1567724869.691 [Reporter](INFO): Rowe_600.Beam1Range 0.000000 m
2019-09-05T23:07:49.691Z,1567724869.691 [Reporter](INFO): Rowe_600.Beam2Range 0.035000 m
2019-09-05T23:07:49.692Z,1567724869.692 [Reporter](INFO): Rowe_600.Beam3Range 0.000000 m
2019-09-05T23:07:49.693Z,1567724869.693 [Reporter](INFO): Rowe_600.Beam4Range 0.038000 m
2019-09-05T23:07:50.013Z,1567724870.013 [CTD_Seabird](INFO): Powering down
2019-09-05T23:07:50.025Z,1567724870.025 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:50.025Z,1567724870.025 [CTD_Seabird](INFO): Powering down
2019-09-05T23:07:50.037Z,1567724870.037 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.053Z,1567724870.053 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-09-05T23:07:50.053Z,1567724870.053 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.053Z,1567724870.053 [Radio_Surface](INFO): Join timeout helper Thread ID is 4781
2019-09-05T23:07:50.209Z,1567724870.209 [Radio_Surface](INFO): Powering down
2019-09-05T23:07:50.210Z,1567724870.210 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:50.210Z,1567724870.210 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.222Z,1567724870.222 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-09-05T23:07:50.222Z,1567724870.222 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.222Z,1567724870.222 [DataOverHttps](INFO): Join timeout helper Thread ID is 4782
2019-09-05T23:07:50.225Z,1567724870.225 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:50.225Z,1567724870.225 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.246Z,1567724870.246 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-09-05T23:07:50.246Z,1567724870.246 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.246Z,1567724870.246 [logger](INFO): Join timeout helper Thread ID is 4783
2019-09-05T23:07:50.253Z,1567724870.253 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:50.253Z,1567724870.253 [logger ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.254Z,1567724870.254 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-09-05T23:07:50.254Z,1567724870.254 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.255Z,1567724870.255 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-09-05T23:07:50.255Z,1567724870.255 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.255Z,1567724870.255 [controlThread](INFO): Join timeout helper Thread ID is 4784
2019-09-05T23:07:50.369Z,1567724870.369 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-09-05T23:07:50.369Z,1567724870.369 [controlThread](DEBUG): Uninitializing ControlThread
2019-09-05T23:07:50.370Z,1567724870.370 [AHRS_M2](INFO): Powering down
2019-09-05T23:07:50.441Z,1567724870.441 [NAL9602](INFO): Powering down
2019-09-05T23:07:50.561Z,1567724870.561 [Rowe_600](INFO): Powering down
2019-09-05T23:07:50.562Z,1567724870.562 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-09-05T23:07:50.563Z,1567724870.563 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-09-05T23:07:50.564Z,1567724870.564 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-09-05T23:07:50.564Z,1567724870.564 [MissionManager](INFO): Uninitializing Mission Default
2019-09-05T23:07:50.564Z,1567724870.564 [Default] Stopped
2019-09-05T23:07:50.565Z,1567724870.565 [Default](DEBUG): Aggregate::uninitialize Default
2019-09-05T23:07:50.565Z,1567724870.565 [Default:B.GoToSurface] Stopped
2019-09-05T23:07:50.565Z,1567724870.565 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-09-05T23:07:50.565Z,1567724870.565 [Default:CheckIn] Stopped
2019-09-05T23:07:50.565Z,1567724870.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-09-05T23:07:50.565Z,1567724870.565 [Default:CheckIn:Read_GPS] Stopped
2019-09-05T23:07:50.567Z,1567724870.567 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-09-05T23:07:50.568Z,1567724870.568 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-09-05T23:07:50.568Z,1567724870.568 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-09-05T23:07:50.568Z,1567724870.568 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-09-05T23:07:50.569Z,1567724870.569 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-09-05T23:07:50.569Z,1567724870.569 [BuoyancyServo](INFO): Powering down
2019-09-05T23:07:50.581Z,1567724870.581 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-09-05T23:07:50.581Z,1567724870.581 [ElevatorServo](INFO): Powering down
2019-09-05T23:07:50.582Z,1567724870.582 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-09-05T23:07:50.582Z,1567724870.582 [MassServo](INFO): Powering down
2019-09-05T23:07:50.583Z,1567724870.583 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-09-05T23:07:50.583Z,1567724870.583 [RudderServo](INFO): Powering down
2019-09-05T23:07:50.583Z,1567724870.583 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-09-05T23:07:50.584Z,1567724870.584 [ThrusterServo](INFO): Powering down
2019-09-05T23:07:50.584Z,1567724870.584 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-09-05T23:07:50.585Z,1567724870.585 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-09-05T23:07:50.585Z,1567724870.585 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-09-05T23:07:50.585Z,1567724870.585 [CBIT](DEBUG): Powering off loads.
2019-09-05T23:07:50.597Z,1567724870.597 [CBIT](DEBUG): Disabling WDT.
2019-09-05T23:07:50.609Z,1567724870.609 [CBIT](DEBUG): Opening all GF detection circuits.
2019-09-05T23:07:50.609Z,1567724870.609 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.688Z,1567724870.688 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.694Z,1567724870.694 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.751Z,1567724870.751 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.753Z,1567724870.753 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.763Z,1567724870.763 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-09-05T23:07:50.823Z,1567724870.823 [logger ThreadHandler](INFO): Thread cancelled.