1970-01-13T21:56:56.481Z,1115816.481 [Supervisor](DEBUG): Initializing supervisor. 1970-01-13T21:56:56.485Z,1115816.485 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 1970-01-13T21:56:56.486Z,1115816.486 [SyncHandler](INFO): Protected caller Thread ID is 6569 1970-01-13T21:56:56.486Z,1115816.486 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 1970-01-13T21:56:56.487Z,1115816.487 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 1970-01-13T21:56:56.487Z,1115816.487 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6570 1970-01-13T21:56:56.491Z,1115816.491 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 1970-01-13T21:56:56.509Z,1115816.509 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 1970-01-13T21:56:56.510Z,1115816.510 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 1970-01-13T21:56:56.510Z,1115816.510 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6571 1970-01-13T21:56:56.514Z,1115816.514 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 1970-01-13T21:56:56.515Z,1115816.515 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 1970-01-13T21:56:56.515Z,1115816.515 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6572 1970-01-13T21:56:56.517Z,1115816.517 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 1970-01-13T21:56:56.518Z,1115816.518 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 1970-01-13T21:56:56.519Z,1115816.519 [logger ThreadHandler](INFO): Protected caller Thread ID is 6573 1970-01-13T21:56:56.522Z,1115816.522 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 1970-01-13T21:56:56.523Z,1115816.523 [Supervisor](INFO): Looking for Config files in directory: Config/ 1970-01-13T21:56:56.524Z,1115816.524 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 1970-01-13T21:56:56.602Z,1115816.602 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 1970-01-13T21:56:56.726Z,1115816.726 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 1970-01-13T21:56:56.727Z,1115816.727 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 1970-01-13T21:56:56.923Z,1115816.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 1970-01-13T21:56:56.924Z,1115816.924 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 1970-01-13T21:56:57.247Z,1115817.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 1970-01-13T21:56:57.247Z,1115817.247 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 1970-01-13T21:56:57.422Z,1115817.422 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 1970-01-13T21:56:57.423Z,1115817.423 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 1970-01-13T21:56:57.782Z,1115817.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 1970-01-13T21:56:57.782Z,1115817.782 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 1970-01-13T21:56:57.887Z,1115817.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 1970-01-13T21:56:57.888Z,1115817.888 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 1970-01-13T21:56:57.981Z,1115817.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 1970-01-13T21:56:57.982Z,1115817.982 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 1970-01-13T21:56:58.194Z,1115818.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 1970-01-13T21:56:58.194Z,1115818.194 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 1970-01-13T21:56:58.386Z,1115818.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 1970-01-13T21:56:58.387Z,1115818.387 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 1970-01-13T21:56:58.465Z,1115818.465 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 1970-01-13T21:56:58.775Z,1115818.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 1970-01-13T21:56:58.776Z,1115818.776 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 1970-01-13T21:56:59.243Z,1115819.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 1970-01-13T21:56:59.244Z,1115819.244 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 1970-01-13T21:56:59.795Z,1115819.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 1970-01-13T21:56:59.796Z,1115819.796 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 1970-01-13T21:57:00.521Z,1115820.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 1970-01-13T21:57:00.522Z,1115820.522 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 1970-01-13T21:57:00.800Z,1115820.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 1970-01-13T21:57:00.802Z,1115820.802 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 1970-01-13T21:57:00.803Z,1115820.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 1970-01-13T21:57:00.926Z,1115820.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 1970-01-13T21:57:01.015Z,1115821.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 1970-01-13T21:57:01.122Z,1115821.122 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 1970-01-13T21:57:01.208Z,1115821.208 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 1970-01-13T21:57:01.315Z,1115821.315 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 1970-01-13T21:57:01.406Z,1115821.406 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 1970-01-13T21:57:01.657Z,1115821.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 1970-01-13T21:57:01.658Z,1115821.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 1970-01-13T21:57:01.807Z,1115821.807 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 1970-01-13T21:57:01.891Z,1115821.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 1970-01-13T21:57:02.035Z,1115822.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg 1970-01-13T21:57:02.134Z,1115822.134 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 1970-01-13T21:57:02.135Z,1115822.135 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 1970-01-13T21:57:02.136Z,1115822.136 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 1970-01-13T21:57:02.233Z,1115822.233 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 1970-01-13T21:57:02.234Z,1115822.234 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 1970-01-13T21:57:02.636Z,1115822.636 [AHRS_M2] Loaded 1970-01-13T21:57:02.636Z,1115822.636 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 1970-01-13T21:57:03.415Z,1115823.415 [BPC1] Loaded 1970-01-13T21:57:03.415Z,1115823.415 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 1970-01-13T21:57:03.489Z,1115823.489 [DataOverHttps] Loaded 1970-01-13T21:57:03.489Z,1115823.489 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 1970-01-13T21:57:03.490Z,1115823.490 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408204E0 1970-01-13T21:57:03.490Z,1115823.490 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6656 1970-01-13T21:57:03.510Z,1115823.510 [Depth_Keller] Loaded 1970-01-13T21:57:03.510Z,1115823.510 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 1970-01-13T21:57:03.515Z,1115823.515 [DropWeight] Loaded 1970-01-13T21:57:03.515Z,1115823.515 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 1970-01-13T21:57:03.576Z,1115823.576 [NAL9602] Loaded 1970-01-13T21:57:03.576Z,1115823.576 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 1970-01-13T21:57:03.603Z,1115823.603 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/voltage_now 1970-01-13T21:57:03.603Z,1115823.603 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/current_now 1970-01-13T21:57:03.604Z,1115823.604 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_now 1970-01-13T21:57:03.604Z,1115823.604 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-0/charge_full 1970-01-13T21:57:03.605Z,1115823.605 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/voltage_now 1970-01-13T21:57:03.605Z,1115823.605 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/current_now 1970-01-13T21:57:03.606Z,1115823.606 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_now 1970-01-13T21:57:03.606Z,1115823.606 [Onboard](ERROR): Can't open SysFS node file: /sys/class/power_supply/ds2782-1/charge_full 1970-01-13T21:57:03.606Z,1115823.606 [Onboard] Loaded 1970-01-13T21:57:03.607Z,1115823.607 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 1970-01-13T21:57:03.607Z,1115823.607 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408504E0 1970-01-13T21:57:03.608Z,1115823.608 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6657 1970-01-13T21:57:03.621Z,1115823.621 [Power24vConverter] Loaded 1970-01-13T21:57:03.621Z,1115823.621 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 1970-01-13T21:57:03.653Z,1115823.653 [PNI_TCM] Loaded 1970-01-13T21:57:03.654Z,1115823.654 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread. 1970-01-13T21:57:03.667Z,1115823.667 [Radio_Surface] Loaded 1970-01-13T21:57:03.668Z,1115823.668 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 1970-01-13T21:57:03.669Z,1115823.669 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408804E0 1970-01-13T21:57:03.669Z,1115823.669 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6658 1970-01-13T21:57:03.756Z,1115823.756 [DAT] Loaded 1970-01-13T21:57:03.757Z,1115823.757 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 1970-01-13T21:57:03.758Z,1115823.758 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408B04E0 1970-01-13T21:57:03.758Z,1115823.758 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6659 1970-01-13T21:57:03.759Z,1115823.759 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 1970-01-13T21:57:03.759Z,1115823.759 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 1970-01-13T21:57:03.786Z,1115823.786 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 1970-01-13T21:57:03.787Z,1115823.787 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 1970-01-13T21:57:03.797Z,1115823.797 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 1970-01-13T21:57:03.797Z,1115823.797 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 1970-01-13T21:57:03.849Z,1115823.849 [DepthRateCalculator] Loaded 1970-01-13T21:57:03.849Z,1115823.849 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 1970-01-13T21:57:03.854Z,1115823.854 [PitchRateCalculator] Loaded 1970-01-13T21:57:03.855Z,1115823.855 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 1970-01-13T21:57:03.865Z,1115823.865 [SpeedCalculator] Loaded 1970-01-13T21:57:03.865Z,1115823.865 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 1970-01-13T21:57:03.870Z,1115823.870 [YawRateCalculator] Loaded 1970-01-13T21:57:03.870Z,1115823.870 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 1970-01-13T21:57:03.892Z,1115823.892 [ElevatorOffsetCalculator] Loaded 1970-01-13T21:57:03.892Z,1115823.892 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 1970-01-13T21:57:03.892Z,1115823.892 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 1970-01-13T21:57:03.893Z,1115823.893 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 1970-01-13T21:57:04.036Z,1115824.036 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 1970-01-13T21:57:04.037Z,1115824.037 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 1970-01-13T21:57:04.093Z,1115824.093 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 1970-01-13T21:57:04.093Z,1115824.093 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 1970-01-13T21:57:04.203Z,1115824.203 [DeadReckonUsingMultipleVelocitySources] Loaded 1970-01-13T21:57:04.203Z,1115824.203 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 1970-01-13T21:57:04.243Z,1115824.243 [DeadReckonUsingSpeedCalculator] Loaded 1970-01-13T21:57:04.243Z,1115824.243 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread. 1970-01-13T21:57:04.284Z,1115824.284 [DeadReckonWithRespectToSeafloor] Loaded 1970-01-13T21:57:04.284Z,1115824.284 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 1970-01-13T21:57:04.297Z,1115824.297 [NavChart] Loaded 1970-01-13T21:57:04.298Z,1115824.298 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 1970-01-13T21:57:04.303Z,1115824.303 [UniversalFixResidualReporter] Loaded 1970-01-13T21:57:04.303Z,1115824.303 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 1970-01-13T21:57:04.304Z,1115824.304 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 1970-01-13T21:57:04.304Z,1115824.304 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 1970-01-13T21:57:04.607Z,1115824.607 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 1970-01-13T21:57:04.608Z,1115824.608 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 1970-01-13T21:57:04.745Z,1115824.745 [SBIT](DEBUG): Construct Startup Built In Test. 1970-01-13T21:57:04.759Z,1115824.759 [SBIT] Loaded 1970-01-13T21:57:04.759Z,1115824.759 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 1970-01-13T21:57:04.762Z,1115824.762 [IBIT](DEBUG): Construct Initiated Built In Test. 1970-01-13T21:57:04.775Z,1115824.775 [IBIT] Loaded 1970-01-13T21:57:04.775Z,1115824.775 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 1970-01-13T21:57:04.781Z,1115824.781 [CBIT](DEBUG): Construct Continuous Built In Test. 1970-01-13T21:57:04.881Z,1115824.881 [CBIT] Loaded 1970-01-13T21:57:04.881Z,1115824.881 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 1970-01-13T21:57:04.882Z,1115824.882 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 1970-01-13T21:57:04.882Z,1115824.882 [Module Loader](DEBUG): Loading Module at Modules/Control.so 1970-01-13T21:57:04.932Z,1115824.932 [VerticalControl](DEBUG): Construct VerticalControl. 1970-01-13T21:57:04.984Z,1115824.984 [VerticalControl] Loaded 1970-01-13T21:57:04.985Z,1115824.985 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 1970-01-13T21:57:04.988Z,1115824.988 [HorizontalControl](DEBUG): Construct HorizontalControl. 1970-01-13T21:57:05.026Z,1115825.026 [HorizontalControl] Loaded 1970-01-13T21:57:05.026Z,1115825.026 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 1970-01-13T21:57:05.028Z,1115825.028 [SpeedControl](DEBUG): Construct SpeedControl. 1970-01-13T21:57:05.031Z,1115825.031 [SpeedControl] Loaded 1970-01-13T21:57:05.031Z,1115825.031 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 1970-01-13T21:57:05.034Z,1115825.034 [LoopControl](DEBUG): Construct LoopControl. 1970-01-13T21:57:05.034Z,1115825.034 [LoopControl] Loaded 1970-01-13T21:57:05.035Z,1115825.035 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 1970-01-13T21:57:05.035Z,1115825.035 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 1970-01-13T21:57:05.035Z,1115825.035 [Module Loader](DEBUG): Loading Module at Modules/Science.so 1970-01-13T21:57:05.170Z,1115825.170 [Aanderaa_O2] Loaded 1970-01-13T21:57:05.170Z,1115825.170 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 1970-01-13T21:57:05.230Z,1115825.230 [CTD_Seabird] Loaded 1970-01-13T21:57:05.230Z,1115825.230 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 1970-01-13T21:57:05.231Z,1115825.231 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B104E0 1970-01-13T21:57:05.231Z,1115825.231 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6660 1970-01-13T21:57:05.263Z,1115825.263 [ESPComponent] Loaded 1970-01-13T21:57:05.264Z,1115825.264 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 1970-01-13T21:57:05.283Z,1115825.283 [PAR_Licor] Loaded 1970-01-13T21:57:05.284Z,1115825.284 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 1970-01-13T21:57:05.314Z,1115825.314 [WetLabsBB2FL] Loaded 1970-01-13T21:57:05.314Z,1115825.314 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 1970-01-13T21:57:05.315Z,1115825.315 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B404E0 1970-01-13T21:57:05.315Z,1115825.315 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6661 1970-01-13T21:57:05.316Z,1115825.316 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 1970-01-13T21:57:05.317Z,1115825.317 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 1970-01-13T21:57:05.446Z,1115825.446 [BuoyancyServo] Loaded 1970-01-13T21:57:05.446Z,1115825.446 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 1970-01-13T21:57:05.466Z,1115825.466 [ElevatorServo] Loaded 1970-01-13T21:57:05.467Z,1115825.467 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 1970-01-13T21:57:05.486Z,1115825.486 [MassServo] Loaded 1970-01-13T21:57:05.486Z,1115825.486 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 1970-01-13T21:57:05.505Z,1115825.505 [RudderServo] Loaded 1970-01-13T21:57:05.505Z,1115825.505 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 1970-01-13T21:57:05.519Z,1115825.519 [ThrusterHE] Loaded 1970-01-13T21:57:05.520Z,1115825.520 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 1970-01-13T21:57:05.520Z,1115825.520 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 1970-01-13T21:57:05.526Z,1115825.526 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 1970-01-13T21:57:05.529Z,1115825.529 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 1970-01-13T21:57:05.540Z,1115825.540 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 1970-01-13T21:57:05.541Z,1115825.541 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BA64E0 1970-01-13T21:57:05.541Z,1115825.541 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6662 1970-01-13T21:57:05.546Z,1115825.546 [Supervisor](INFO): Main Thread ID is 6568 1970-01-13T21:57:05.546Z,1115825.546 [Supervisor](DEBUG): Running supervisor. 1970-01-13T21:57:05.546Z,1115825.546 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6663 1970-01-13T21:57:05.547Z,1115825.547 [CommandExec](INFO): Initializing the command executive. 1970-01-13T21:57:05.548Z,1115825.548 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6664 1970-01-13T21:57:05.550Z,1115825.550 [controlThread ThreadHandler](INFO): Handler Thread ID is 6665 1970-01-13T21:57:05.551Z,1115825.551 [controlThread](DEBUG): Initializing ControlThread 1970-01-13T21:57:05.555Z,1115825.555 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 1970-01-13T21:57:05.555Z,1115825.555 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 1970-01-13T21:57:05.555Z,1115825.555 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 1970-01-13T21:57:05.556Z,1115825.556 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 1970-01-13T21:57:05.556Z,1115825.556 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 1970-01-13T21:57:05.557Z,1115825.557 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 1970-01-13T21:57:05.558Z,1115825.558 [NavChart](DEBUG): Initialize NavChart Navigation. 1970-01-13T21:57:05.558Z,1115825.558 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 1970-01-13T21:57:05.558Z,1115825.558 [SBIT](INFO): Initialize SBIT Component. 1970-01-13T21:57:05.559Z,1115825.559 [SBIT](IMPORTANT): git: 2023-01-26 1970-01-13T21:57:05.559Z,1115825.559 [SBIT](INFO): git hash: fbcd350f88251f9a867f3fca05699dec8f096e0d 1970-01-13T21:57:05.559Z,1115825.559 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 1970-01-13T21:57:05.561Z,1115825.561 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 1970-01-13T21:57:05.562Z,1115825.562 [SBIT](INFO): Beginning SBIT in 71.000000 seconds. 1970-01-13T21:57:05.563Z,1115825.563 [IBIT](INFO): Initialize IBIT Component. 1970-01-13T21:57:05.563Z,1115825.563 [CBIT](DEBUG): Initialize CBIT Component. 1970-01-13T21:57:05.564Z,1115825.564 [logger ThreadHandler](INFO): Handler Thread ID is 6666 1970-01-13T21:57:05.577Z,1115825.577 [CBIT](DEBUG): Initialized mux pins. 1970-01-13T21:57:05.577Z,1115825.577 [CBIT](DEBUG): Initializing the watchdog timer. 1970-01-13T21:57:05.585Z,1115825.585 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6667 1970-01-13T21:57:05.586Z,1115825.586 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 1970-01-13T21:57:05.597Z,1115825.597 [Onboard ThreadHandler](INFO): Handler Thread ID is 6668 1970-01-13T21:57:05.601Z,1115825.601 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 1970-01-13T21:57:05.602Z,1115825.602 [CBIT](DEBUG): Initializing heartbeat. 1970-01-13T21:57:05.620Z,1115825.620 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6669 1970-01-13T21:57:05.622Z,1115825.622 [DAT ThreadHandler](INFO): Handler Thread ID is 6670 1970-01-13T21:57:05.622Z,1115825.622 [DAT](INFO): Powering up 1970-01-13T21:57:05.623Z,1115825.623 [DAT](DEBUG): Initializing DAT. 1970-01-13T21:57:05.626Z,1115825.626 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6672 1970-01-13T21:57:05.627Z,1115825.627 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T21:57:05.630Z,1115825.630 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6674 1970-01-13T21:57:05.633Z,1115825.633 [WetLabsBB2FL](INFO): Powering up 1970-01-13T21:57:05.634Z,1115825.634 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6676 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 1970-01-13T21:57:05.638Z,1115825.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000 1970-01-13T21:57:05.639Z,1115825.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000 1970-01-13T21:57:05.673Z,1115825.673 [CBIT](DEBUG): Deactivating GF circuits. 1970-01-13T21:57:05.673Z,1115825.673 [CBIT](DEBUG): Deactivating emergency mode. 1970-01-13T21:57:05.709Z,1115825.709 [CBIT](DEBUG): Backplane powered. 1970-01-13T21:57:05.709Z,1115825.709 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 1970-01-13T21:57:05.711Z,1115825.711 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 1970-01-13T21:57:05.712Z,1115825.712 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 1970-01-13T21:57:05.712Z,1115825.712 [LoopControl](DEBUG): Initialize LoopControlComponent. 1970-01-13T21:57:05.719Z,1115825.719 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml 1970-01-13T21:57:05.744Z,1115825.744 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 1970-01-13T21:57:05.779Z,1115825.779 [MissionManager](DEBUG): 1970-01-13T21:57:05.780Z,1115825.780 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml 1970-01-13T21:57:05.853Z,1115825.853 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 1970-01-13T21:57:05.854Z,1115825.854 [Default:A.Wait](DEBUG): Construct Wait. 1970-01-13T21:57:05.877Z,1115825.877 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 1970-01-13T21:57:05.904Z,1115825.904 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 1970-01-13T21:57:05.906Z,1115825.906 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 1970-01-13T21:57:05.932Z,1115825.932 [Default:E.Execute](DEBUG): Construct Execute. 1970-01-13T21:57:05.936Z,1115825.936 [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 1970-01-13T21:57:05.953Z,1115825.953 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 1970-01-13T21:57:05.959Z,1115825.959 [AHRS_M2](DEBUG): Initializing AHRS_M2. 1970-01-13T21:57:06.021Z,1115826.021 [Depth_Keller](ERROR): Pressure reading out of range: 1727.332275 decibar 1970-01-13T21:57:06.022Z,1115826.022 [Power24vConverter](INFO): Powering up. 1970-01-13T21:57:06.025Z,1115826.025 [Radio_Surface](INFO): Powering up 1970-01-13T21:57:06.043Z,1115826.043 [DepthRateCalculator](ERROR): Depth measurement is not active 1970-01-13T21:57:06.081Z,1115826.081 [BuoyancyServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:06.089Z,1115826.089 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 1970-01-13T21:57:06.090Z,1115826.090 [ElevatorServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:06.097Z,1115826.097 [ElevatorServo](DEBUG): Initializing ElevatorServo. 1970-01-13T21:57:06.098Z,1115826.098 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:06.105Z,1115826.105 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:06.106Z,1115826.106 [RudderServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:06.113Z,1115826.113 [RudderServo](DEBUG): Initializing RudderServo. 1970-01-13T21:57:06.114Z,1115826.114 [ThrusterHE](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:06.126Z,1115826.126 [ThrusterHE](DEBUG): Initializing ThrusterHE. 1970-01-13T21:57:06.411Z,1115826.411 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 1970-01-13T21:57:06.411Z,1115826.411 [DropWeight] Hardware Fault, FailCount= 1 1970-01-13T21:57:06.411Z,1115826.411 [DropWeight](ERROR): Hardware Fault 1970-01-13T21:57:06.471Z,1115826.471 [CommandExec](FAULT): Scheduling is paused 1970-01-13T21:57:06.471Z,1115826.471 [CBIT](INFO): Critical error at 19700113T215706 1970-01-13T21:57:06.472Z,1115826.472 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 1970-01-13T21:57:06.474Z,1115826.474 [CBIT](ERROR): Hardware Fault in component: DropWeight 1970-01-13T21:57:06.475Z,1115826.475 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 1970-01-13T21:57:06.850Z,1115826.850 [CBIT](INFO): Critical error at 19700113T215706 1970-01-13T21:57:08.769Z,1115828.769 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T21:57:08.769Z,1115828.769 [MassServo] Communications Fault, FailCount= 1 1970-01-13T21:57:08.769Z,1115828.769 [MassServo](ERROR): Communications Fault 1970-01-13T21:57:08.802Z,1115828.802 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T21:57:08.921Z,1115828.921 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T21:57:08.921Z,1115828.921 [MassServo](INFO): Powering down 1970-01-13T21:57:09.211Z,1115829.211 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop 1970-01-13T21:57:09.613Z,1115829.613 [AHRS_M2](DEBUG): chan0Format 2 set drop 1970-01-13T21:57:09.708Z,1115829.708 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:09.826Z,1115829.826 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:09.857Z,1115829.857 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T21:57:09.857Z,1115829.857 [MassServo] No Fault, FailCount= 1 1970-01-13T21:57:10.018Z,1115830.018 [AHRS_M2](DEBUG): chan0Trigger 5 set drop 1970-01-13T21:57:10.422Z,1115830.422 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop 1970-01-13T21:57:10.422Z,1115830.422 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop 1970-01-13T21:57:10.825Z,1115830.825 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop 1970-01-13T21:57:10.921Z,1115830.921 [WetLabsBB2FL] Communications Fault, FailCount= 1 1970-01-13T21:57:10.921Z,1115830.921 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T21:57:11.230Z,1115831.230 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop 1970-01-13T21:57:11.261Z,1115831.261 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:11.421Z,1115831.421 [WetLabsBB2FL](INFO): Powering down 1970-01-13T21:57:11.633Z,1115831.633 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop 1970-01-13T21:57:12.038Z,1115832.038 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop 1970-01-13T21:57:12.417Z,1115832.417 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T21:57:12.417Z,1115832.417 [MassServo] Communications Fault, FailCount= 2 1970-01-13T21:57:12.417Z,1115832.417 [MassServo](ERROR): Communications Fault 1970-01-13T21:57:12.480Z,1115832.480 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T21:57:12.480Z,1115832.480 [WetLabsBB2FL] No Fault, FailCount= 1 1970-01-13T21:57:12.481Z,1115832.481 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T21:57:12.487Z,1115832.487 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop 1970-01-13T21:57:12.514Z,1115832.514 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T21:57:12.514Z,1115832.514 [MassServo](INFO): Powering down 1970-01-13T21:57:12.639Z,1115832.639 [WetLabsBB2FL](INFO): Powering up 1970-01-13T21:57:12.889Z,1115832.889 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop 1970-01-13T21:57:13.294Z,1115833.294 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop 1970-01-13T21:57:13.299Z,1115833.299 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T21:57:13.299Z,1115833.299 [PNI_TCM] Data Fault, FailCount= 1 1970-01-13T21:57:13.299Z,1115833.299 [PNI_TCM](ERROR): Data Fault 1970-01-13T21:57:13.311Z,1115833.311 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:13.430Z,1115833.430 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:13.435Z,1115833.435 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T21:57:13.435Z,1115833.435 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T21:57:13.435Z,1115833.435 [MassServo] No Fault, FailCount= 2 1970-01-13T21:57:13.698Z,1115833.698 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop 1970-01-13T21:57:13.706Z,1115833.706 [PNI_TCM](INFO): Powering down 1970-01-13T21:57:14.101Z,1115834.101 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop 1970-01-13T21:57:14.956Z,1115834.956 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T21:57:14.956Z,1115834.956 [PNI_TCM] No Fault, FailCount= 1 1970-01-13T21:57:16.045Z,1115836.045 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T21:57:16.045Z,1115836.045 [MassServo] Communications Fault, FailCount= 3 1970-01-13T21:57:16.045Z,1115836.045 [MassServo](ERROR): Communications Fault 1970-01-13T21:57:16.049Z,1115836.049 [CBIT](CRITICAL): Environmental Failure. Press:14.877971 PSI. Humidity:29%. Temp:25 C. ABORTING MISSION 1970-01-13T21:57:16.050Z,1115836.050 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T21:57:16.153Z,1115836.153 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:16.153Z,1115836.153 [Aanderaa_O2] Communications Fault, FailCount= 1 1970-01-13T21:57:16.153Z,1115836.153 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T21:57:16.192Z,1115836.192 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T21:57:16.192Z,1115836.192 [MassServo](INFO): Powering down 1970-01-13T21:57:16.203Z,1115836.203 [CBIT](INFO): Critical error at 19700113T215716 1970-01-13T21:57:16.221Z,1115836.221 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T21:57:16.657Z,1115836.657 [Aanderaa_O2](INFO): Powering down 1970-01-13T21:57:17.003Z,1115837.003 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:17.122Z,1115837.122 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:17.126Z,1115837.126 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T21:57:17.126Z,1115837.126 [MassServo] No Fault, FailCount= 3 1970-01-13T21:57:17.438Z,1115837.438 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T21:57:17.438Z,1115837.438 [Aanderaa_O2] No Fault, FailCount= 1 1970-01-13T21:57:17.929Z,1115837.929 [WetLabsBB2FL] Communications Fault, FailCount= 2 1970-01-13T21:57:17.929Z,1115837.929 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T21:57:18.225Z,1115838.225 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:18.429Z,1115838.429 [WetLabsBB2FL](INFO): Powering down 1970-01-13T21:57:18.677Z,1115838.677 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:18.677Z,1115838.677 [CTD_Seabird] Communications Fault, FailCount= 1 1970-01-13T21:57:18.677Z,1115838.677 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T21:57:19.013Z,1115839.013 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T21:57:19.081Z,1115839.081 [CTD_Seabird](INFO): Powering down 1970-01-13T21:57:19.721Z,1115839.721 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T21:57:19.721Z,1115839.721 [MassServo] Communications Fault, FailCount= 4 1970-01-13T21:57:19.721Z,1115839.721 [MassServo](ERROR): Communications Fault 1970-01-13T21:57:19.725Z,1115839.725 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T21:57:19.725Z,1115839.725 [WetLabsBB2FL] No Fault, FailCount= 2 1970-01-13T21:57:19.725Z,1115839.725 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T21:57:19.803Z,1115839.803 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T21:57:19.803Z,1115839.803 [MassServo](INFO): Powering down 1970-01-13T21:57:20.060Z,1115840.060 [WetLabsBB2FL](INFO): Powering up 1970-01-13T21:57:20.215Z,1115840.215 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1970-01-13T21:57:20.215Z,1115840.215 [CTD_Seabird] No Fault, FailCount= 1 1970-01-13T21:57:20.425Z,1115840.425 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T21:57:20.635Z,1115840.635 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:20.754Z,1115840.754 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:20.759Z,1115840.759 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T21:57:20.759Z,1115840.759 [MassServo] No Fault, FailCount= 4 1970-01-13T21:57:22.630Z,1115842.630 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T21:57:22.630Z,1115842.630 [PNI_TCM] Data Fault, FailCount= 2 1970-01-13T21:57:22.631Z,1115842.631 [PNI_TCM](ERROR): Data Fault 1970-01-13T21:57:22.696Z,1115842.696 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T21:57:23.030Z,1115843.030 [PNI_TCM](INFO): Powering down 1970-01-13T21:57:23.485Z,1115843.485 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T21:57:23.485Z,1115843.485 [MassServo] Communications Fault, FailCount= 5 1970-01-13T21:57:23.485Z,1115843.485 [MassServo](ERROR): Communications Fault 1970-01-13T21:57:23.513Z,1115843.513 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T21:57:23.514Z,1115843.514 [CBIT](CRITICAL): Communications Fault in component: MassServo 1970-01-13T21:57:23.578Z,1115843.578 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T21:57:23.578Z,1115843.578 [MassServo](INFO): Powering down 1970-01-13T21:57:23.585Z,1115843.585 [CBIT](INFO): Critical error at 19700113T215723 1970-01-13T21:57:24.327Z,1115844.327 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 1970-01-13T21:57:24.327Z,1115844.327 [AHRS_M2] Data Fault, FailCount= 1 1970-01-13T21:57:24.327Z,1115844.327 [AHRS_M2](ERROR): Data Fault 1970-01-13T21:57:24.399Z,1115844.399 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T21:57:24.533Z,1115844.533 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T21:57:24.537Z,1115844.537 [CBIT](ERROR): Data Fault in component: AHRS_M2 1970-01-13T21:57:24.538Z,1115844.538 [CBIT](CRITICAL): Data Fault in component: AHRS_M2 1970-01-13T21:57:24.538Z,1115844.538 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T21:57:24.538Z,1115844.538 [PNI_TCM] No Fault, FailCount= 2 1970-01-13T21:57:24.769Z,1115844.769 [CBIT](INFO): Critical error at 19700113T215724 1970-01-13T21:57:25.599Z,1115845.599 [WetLabsBB2FL] Communications Fault, FailCount= 3 1970-01-13T21:57:25.599Z,1115845.599 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T21:57:25.997Z,1115845.997 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:26.205Z,1115846.205 [WetLabsBB2FL](INFO): Powering down 1970-01-13T21:57:27.238Z,1115847.238 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T21:57:27.238Z,1115847.238 [WetLabsBB2FL] No Fault, FailCount= 3 1970-01-13T21:57:27.517Z,1115847.517 [WetLabsBB2FL](INFO): Powering up 1970-01-13T21:57:28.063Z,1115848.063 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:28.063Z,1115848.063 [Aanderaa_O2] Communications Fault, FailCount= 2 1970-01-13T21:57:28.063Z,1115848.063 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T21:57:28.096Z,1115848.096 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T21:57:28.513Z,1115848.513 [Aanderaa_O2](INFO): Powering down 1970-01-13T21:57:29.238Z,1115849.238 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T21:57:29.238Z,1115849.238 [Aanderaa_O2] No Fault, FailCount= 2 1970-01-13T21:57:32.068Z,1115852.068 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T21:57:32.069Z,1115852.069 [PNI_TCM] Data Fault, FailCount= 3 1970-01-13T21:57:32.069Z,1115852.069 [PNI_TCM](ERROR): Data Fault 1970-01-13T21:57:32.115Z,1115852.115 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T21:57:32.466Z,1115852.466 [PNI_TCM](INFO): Powering down 1970-01-13T21:57:32.969Z,1115852.969 [WetLabsBB2FL] Communications Fault, FailCount= 4 1970-01-13T21:57:32.969Z,1115852.969 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T21:57:32.993Z,1115852.993 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:33.469Z,1115853.469 [WetLabsBB2FL](INFO): Powering down 1970-01-13T21:57:33.565Z,1115853.565 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:33.565Z,1115853.565 [CTD_Seabird] Communications Fault, FailCount= 2 1970-01-13T21:57:33.565Z,1115853.565 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T21:57:33.722Z,1115853.722 [NAL9602](INFO): Powering up NAL9602 1970-01-13T21:57:33.739Z,1115853.739 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T21:57:33.739Z,1115853.739 [PNI_TCM] No Fault, FailCount= 3 1970-01-13T21:57:33.740Z,1115853.740 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T21:57:33.969Z,1115853.969 [CTD_Seabird](INFO): Powering down 1970-01-13T21:57:34.544Z,1115854.544 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T21:57:34.544Z,1115854.544 [WetLabsBB2FL] No Fault, FailCount= 4 1970-01-13T21:57:34.682Z,1115854.682 [WetLabsBB2FL](INFO): Powering up 1970-01-13T21:57:34.974Z,1115854.974 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1970-01-13T21:57:34.974Z,1115854.974 [CTD_Seabird] No Fault, FailCount= 2 1970-01-13T21:57:35.325Z,1115855.325 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T21:57:39.788Z,1115859.788 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:39.788Z,1115859.788 [Aanderaa_O2] Communications Fault, FailCount= 3 1970-01-13T21:57:39.788Z,1115859.788 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T21:57:39.825Z,1115859.825 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T21:57:39.826Z,1115859.826 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 1970-01-13T21:57:39.977Z,1115859.977 [WetLabsBB2FL] Communications Fault, FailCount= 5 1970-01-13T21:57:39.977Z,1115859.977 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T21:57:40.305Z,1115860.305 [Aanderaa_O2](INFO): Powering down 1970-01-13T21:57:40.339Z,1115860.339 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:40.339Z,1115860.339 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 1970-01-13T21:57:40.477Z,1115860.477 [WetLabsBB2FL](INFO): Powering down 1970-01-13T21:57:41.403Z,1115861.403 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T21:57:41.403Z,1115861.403 [PNI_TCM] Data Fault, FailCount= 4 1970-01-13T21:57:41.403Z,1115861.403 [PNI_TCM](ERROR): Data Fault 1970-01-13T21:57:41.438Z,1115861.438 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T21:57:41.800Z,1115861.800 [PNI_TCM](INFO): Powering down 1970-01-13T21:57:43.041Z,1115863.041 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T21:57:43.041Z,1115863.041 [PNI_TCM] No Fault, FailCount= 4 1970-01-13T21:57:44.635Z,1115864.635 [NAL9602](INFO): NAL9602 initialized 1970-01-13T21:57:48.377Z,1115868.377 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T21:57:48.377Z,1115868.377 [CTD_Seabird] Communications Fault, FailCount= 3 1970-01-13T21:57:48.377Z,1115868.377 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T21:57:48.708Z,1115868.708 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T21:57:48.708Z,1115868.708 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 1970-01-13T21:57:48.781Z,1115868.781 [CTD_Seabird](INFO): Powering down 1970-01-13T21:57:50.695Z,1115870.695 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T21:57:50.695Z,1115870.695 [PNI_TCM] Data Fault, FailCount= 5 1970-01-13T21:57:50.695Z,1115870.695 [PNI_TCM](ERROR): Data Fault 1970-01-13T21:57:50.712Z,1115870.712 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T21:57:50.712Z,1115870.712 [CBIT](CRITICAL): Data Fault in component: PNI_TCM 1970-01-13T21:57:51.095Z,1115871.095 [PNI_TCM](INFO): Powering down 1970-01-13T21:57:51.200Z,1115871.200 [CBIT](INFO): Critical error at 19700113T215750 1970-01-13T21:58:05.681Z,1115885.681 [DAT](FAULT): failed to initialize, no bytes available on serial interface 1970-01-13T21:58:05.681Z,1115885.681 [DAT] Communications Fault, FailCount= 1 1970-01-13T21:58:05.681Z,1115885.681 [DAT](ERROR): Communications Fault 1970-01-13T21:58:06.065Z,1115886.065 [CBIT](ERROR): Communications Fault in component: DAT 1970-01-13T21:58:06.085Z,1115886.085 [DAT](INFO): Powering down 1970-01-13T21:58:07.275Z,1115887.275 [CBIT](INFO): Clearing failed state for component DAT 1970-01-13T21:58:07.275Z,1115887.275 [DAT] No Fault, FailCount= 1 1970-01-13T21:58:09.129Z,1115889.129 [DAT](INFO): Powering up 1970-01-13T21:58:09.129Z,1115889.129 [DAT](DEBUG): Initializing DAT. 1970-01-13T21:58:12.907Z,1115892.907 [BPC1](ERROR): BPC1A: No match for serial number 01CE in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 1970-01-13T21:58:13.322Z,1115893.322 [BPC1](ERROR): BPC1B: No match for serial number 0178 in BPC1B's battery stick inventory (sticks 32-63 in onboard configuration file). 1970-01-13T21:58:13.322Z,1115893.322 [BPC1](FAULT): Failed to parse data from all battery packs. 1970-01-13T21:58:13.322Z,1115893.322 [BPC1] Data Fault, FailCount= 1 1970-01-13T21:58:13.322Z,1115893.322 [BPC1](ERROR): Data Fault 1970-01-13T21:58:13.370Z,1115893.370 [CBIT](ERROR): Data Fault in component: BPC1 1970-01-13T21:58:16.963Z,1115896.963 [SBIT](IMPORTANT): Beginning Startup BIT 1970-01-13T21:58:16.968Z,1115896.968 [CBIT](IMPORTANT): Beginning ground fault scan 1970-01-13T21:58:27.881Z,1115907.881 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.015240 CHAN A1 (24V): 0.010139 CHAN A2 (12V): 0.000284 CHAN A3 (5V): -0.001631 CHAN B0 (3.3V): 0.000307 CHAN B1 (3.15aV): 0.000305 CHAN B2 (3.15bV): 0.000310 CHAN B3 (GND): -0.000263 OPEN: 0.005427 Full Scale: +/- 1 mA 1970-01-13T21:58:30.321Z,1115910.321 [SBIT](ERROR): Could not read massPosReader_. 1970-01-13T21:58:56.976Z,1115936.976 [SBIT](ERROR): Could not read massPosReader_. 1970-01-13T21:58:56.976Z,1115936.976 [SBIT](FAULT): Control surface position failure. 1970-01-13T21:59:05.844Z,1115945.844 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T21:59:05.845Z,1115945.845 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 1970-01-13T21:59:05.845Z,1115945.845 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 1970-01-13T21:59:05.846Z,1115945.846 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T21:59:05.846Z,1115945.846 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 1970-01-13T21:59:05.847Z,1115945.847 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 1970-01-13T21:59:05.848Z,1115945.848 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T21:59:05.848Z,1115945.848 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 1970-01-13T21:59:05.848Z,1115945.848 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 1970-01-13T21:59:05.859Z,1115945.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 1970-01-13T21:59:05.859Z,1115945.859 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 1970-01-13T21:59:05.859Z,1115945.859 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 1970-01-13T21:59:06.275Z,1115946.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 1970-01-13T21:59:06.275Z,1115946.275 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 1970-01-13T21:59:06.275Z,1115946.275 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 1970-01-13T21:59:06.275Z,1115946.275 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 1970-01-13T21:59:06.276Z,1115946.276 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 1970-01-13T21:59:06.276Z,1115946.276 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 1970-01-13T21:59:06.668Z,1115946.668 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 1970-01-13T21:59:09.141Z,1115949.141 [DAT](FAULT): failed to initialize, no bytes available on serial interface 1970-01-13T21:59:09.141Z,1115949.141 [DAT] Communications Fault, FailCount= 2 1970-01-13T21:59:09.141Z,1115949.141 [DAT](ERROR): Communications Fault 1970-01-13T21:59:09.493Z,1115949.493 [CBIT](ERROR): Communications Fault in component: DAT 1970-01-13T21:59:09.545Z,1115949.545 [DAT](INFO): Powering down 1970-01-13T21:59:10.713Z,1115950.713 [SBIT](ERROR): Could not read massPosReader_. 1970-01-13T21:59:10.713Z,1115950.713 [SBIT](FAULT): Control surface position failure. 1970-01-13T21:59:10.717Z,1115950.717 [CBIT](INFO): Clearing failed state for component DAT 1970-01-13T21:59:10.717Z,1115950.717 [DAT] No Fault, FailCount= 2 1970-01-13T21:59:11.105Z,1115951.105 [SBIT](CRITICAL): SBIT FAILED 1970-01-13T21:59:11.105Z,1115951.105 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 1970-01-13T21:59:11.106Z,1115951.106 [SBIT](IMPORTANT): No configSet variables persisted 1970-01-13T21:59:11.107Z,1115951.107 [CBIT](INFO): Critical error at 19700113T215911 1970-01-13T21:59:11.519Z,1115951.519 [MissionManager](IMPORTANT): Started mission Startup 1970-01-13T21:59:11.520Z,1115951.520 [Startup] Running Loop=1 1970-01-13T21:59:11.520Z,1115951.520 [Startup](DEBUG): Aggregate::initialize Startup 1970-01-13T21:59:11.520Z,1115951.520 [Startup:A.GoToSurface] Running Loop=1 1970-01-13T21:59:11.520Z,1115951.520 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 1970-01-13T21:59:11.520Z,1115951.520 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 1970-01-13T21:59:11.522Z,1115951.522 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 1970-01-13T21:59:11.522Z,1115951.522 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 1970-01-13T21:59:11.522Z,1115951.522 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 1970-01-13T21:59:11.523Z,1115951.523 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 1970-01-13T21:59:11.524Z,1115951.524 [Startup:StartupSatComms] Running Loop=1 1970-01-13T21:59:11.524Z,1115951.524 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 1970-01-13T21:59:11.524Z,1115951.524 [Startup:StartupSatComms:A] Running Loop=1 1970-01-13T21:59:11.913Z,1115951.913 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 1970-01-13T21:59:12.597Z,1115952.597 [DAT](INFO): Powering up 1970-01-13T21:59:12.597Z,1115952.597 [DAT](DEBUG): Initializing DAT. 1970-01-13T21:59:36.929Z,1115976.929 [CommandExec](IMPORTANT): got command failComponent 1970-01-13T21:59:36.930Z,1115976.930 [CommandExec](IMPORTANT): Failed components: 1970-01-13T21:59:36.930Z,1115976.930 [CommandExec](IMPORTANT): AHRS_M2: Data Fault 1970-01-13T21:59:36.930Z,1115976.930 [CommandExec](IMPORTANT): BPC1: Data Fault 1970-01-13T21:59:36.930Z,1115976.930 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 1970-01-13T21:59:36.930Z,1115976.930 [CommandExec](IMPORTANT): PNI_TCM: Data Fault 1970-01-13T21:59:36.931Z,1115976.931 [CommandExec](IMPORTANT): Aanderaa_O2: Communications Fault 1970-01-13T21:59:36.931Z,1115976.931 [CommandExec](IMPORTANT): CTD_Seabird: Communications Fault 1970-01-13T21:59:36.931Z,1115976.931 [CommandExec](IMPORTANT): WetLabsBB2FL: Communications Fault 1970-01-13T21:59:36.931Z,1115976.931 [CommandExec](IMPORTANT): MassServo: Communications Fault 1970-01-13T21:59:59.883Z,1115999.883 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 1970-01-13T21:59:59.883Z,1115999.883 [CommandExec](FAULT): Incomplete syntax. Try: help maintain 1970-01-13T22:00:07.164Z,1116007.164 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree 1970-01-13T22:00:07.168Z,1116007.168 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread. 1970-01-13T22:00:07.292Z,1116007.292 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 1970-01-13T22:00:10.153Z,1116010.153 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T22:00:10.153Z,1116010.153 [Aanderaa_O2] No Fault, FailCount= 3 1970-01-13T22:00:10.154Z,1116010.154 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:00:10.154Z,1116010.154 [WetLabsBB2FL] No Fault, FailCount= 5 1970-01-13T22:00:10.362Z,1116010.362 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:00:11.711Z,1116011.711 [Startup:StartupSatComms:A](INFO): Timed out from 1970-01-13T21:59:11.5Z 1970-01-13T22:00:11.711Z,1116011.711 [Startup:StartupSatComms:A] Stopped 1970-01-13T22:00:11.711Z,1116011.711 [Startup:StartupSatComms:B] Running Loop=1 1970-01-13T22:00:12.128Z,1116012.128 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 1970-01-13T22:00:12.657Z,1116012.657 [DAT](FAULT): failed to initialize, no bytes available on serial interface 1970-01-13T22:00:12.657Z,1116012.657 [DAT] Communications Fault, FailCount= 3 1970-01-13T22:00:12.657Z,1116012.657 [DAT](ERROR): Communications Fault 1970-01-13T22:00:12.790Z,1116012.790 [CBIT](ERROR): Communications Fault in component: DAT 1970-01-13T22:00:13.101Z,1116013.101 [DAT](INFO): Powering down 1970-01-13T22:00:14.373Z,1116014.373 [CBIT](INFO): Clearing failed state for component DAT 1970-01-13T22:00:14.373Z,1116014.373 [DAT] No Fault, FailCount= 3 1970-01-13T22:00:15.653Z,1116015.653 [WetLabsBB2FL] Communications Fault, FailCount= 1 1970-01-13T22:00:15.653Z,1116015.653 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:00:15.977Z,1116015.977 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:16.149Z,1116016.149 [DAT](INFO): Powering up 1970-01-13T22:00:16.149Z,1116016.149 [DAT](DEBUG): Initializing DAT. 1970-01-13T22:00:16.165Z,1116016.165 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:00:17.193Z,1116017.193 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:00:17.193Z,1116017.193 [WetLabsBB2FL] No Fault, FailCount= 1 1970-01-13T22:00:17.378Z,1116017.378 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:00:18.401Z,1116018.401 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1970-01-13T22:00:18.401Z,1116018.401 [CTD_Seabird] No Fault, FailCount= 3 1970-01-13T22:00:18.797Z,1116018.797 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T22:00:20.154Z,1116020.154 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree 1970-01-13T22:00:20.751Z,1116020.751 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T22:00:20.751Z,1116020.751 [Aanderaa_O2] Communications Fault, FailCount= 1 1970-01-13T22:00:20.751Z,1116020.751 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T22:00:20.829Z,1116020.829 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T22:00:21.273Z,1116021.273 [Aanderaa_O2](INFO): Powering down 1970-01-13T22:00:22.015Z,1116022.015 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T22:00:22.015Z,1116022.015 [Aanderaa_O2] No Fault, FailCount= 1 1970-01-13T22:00:22.685Z,1116022.685 [WetLabsBB2FL] Communications Fault, FailCount= 2 1970-01-13T22:00:22.685Z,1116022.685 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:00:22.843Z,1116022.843 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:23.189Z,1116023.189 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:00:24.029Z,1116024.029 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:00:24.029Z,1116024.029 [WetLabsBB2FL] No Fault, FailCount= 2 1970-01-13T22:00:24.402Z,1116024.402 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:00:29.701Z,1116029.701 [WetLabsBB2FL] Communications Fault, FailCount= 3 1970-01-13T22:00:29.701Z,1116029.701 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:00:30.081Z,1116030.081 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:30.201Z,1116030.201 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:00:30.358Z,1116030.358 [CommandExec](IMPORTANT): got command maintain clear 1970-01-13T22:00:30.479Z,1116030.479 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 1970-01-13T22:00:31.281Z,1116031.281 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:00:31.281Z,1116031.281 [WetLabsBB2FL] No Fault, FailCount= 3 1970-01-13T22:00:31.414Z,1116031.414 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:00:31.857Z,1116031.857 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T22:00:31.857Z,1116031.857 [CTD_Seabird] Communications Fault, FailCount= 1 1970-01-13T22:00:31.857Z,1116031.857 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T22:00:32.137Z,1116032.137 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T22:00:32.261Z,1116032.261 [CTD_Seabird](INFO): Powering down 1970-01-13T22:00:32.468Z,1116032.468 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T22:00:32.468Z,1116032.468 [Aanderaa_O2] Communications Fault, FailCount= 2 1970-01-13T22:00:32.468Z,1116032.468 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T22:00:32.514Z,1116032.514 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T22:00:32.989Z,1116032.989 [Aanderaa_O2](INFO): Powering down 1970-01-13T22:00:33.325Z,1116033.325 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1970-01-13T22:00:33.325Z,1116033.325 [CTD_Seabird] No Fault, FailCount= 1 1970-01-13T22:00:33.605Z,1116033.605 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T22:00:33.728Z,1116033.728 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T22:00:33.728Z,1116033.728 [Aanderaa_O2] No Fault, FailCount= 2 1970-01-13T22:00:36.713Z,1116036.713 [WetLabsBB2FL] Communications Fault, FailCount= 4 1970-01-13T22:00:36.713Z,1116036.713 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:00:36.928Z,1116036.928 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:37.213Z,1116037.213 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:00:38.148Z,1116038.148 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:00:38.148Z,1116038.148 [WetLabsBB2FL] No Fault, FailCount= 4 1970-01-13T22:00:38.426Z,1116038.426 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:00:43.717Z,1116043.717 [WetLabsBB2FL] Communications Fault, FailCount= 5 1970-01-13T22:00:43.717Z,1116043.717 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:00:43.808Z,1116043.808 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:43.808Z,1116043.808 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:00:44.188Z,1116044.188 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 1970-01-13T22:00:44.188Z,1116044.188 [Aanderaa_O2] Communications Fault, FailCount= 3 1970-01-13T22:00:44.189Z,1116044.189 [Aanderaa_O2](ERROR): Communications Fault 1970-01-13T22:00:44.207Z,1116044.207 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 1970-01-13T22:00:44.207Z,1116044.207 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 1970-01-13T22:00:44.217Z,1116044.217 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:00:44.701Z,1116044.701 [Aanderaa_O2](INFO): Powering down 1970-01-13T22:00:46.657Z,1116046.657 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T22:00:46.657Z,1116046.657 [CTD_Seabird] Communications Fault, FailCount= 2 1970-01-13T22:00:46.657Z,1116046.657 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T22:00:47.029Z,1116047.029 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T22:00:47.061Z,1116047.061 [CTD_Seabird](INFO): Powering down 1970-01-13T22:00:48.250Z,1116048.250 [CBIT](INFO): Clearing failed state for component CTD_Seabird 1970-01-13T22:00:48.251Z,1116048.251 [CTD_Seabird] No Fault, FailCount= 2 1970-01-13T22:00:48.405Z,1116048.405 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 1970-01-13T22:00:55.163Z,1116055.163 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 1970-01-13T22:00:55.166Z,1116055.166 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.elevatorAngleAction" handled in the control thread. 1970-01-13T22:00:55.523Z,1116055.523 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.elevatorAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 1970-01-13T22:01:01.457Z,1116061.457 [CTD_Seabird](FAULT): Failed to initialize within timeout. 1970-01-13T22:01:01.457Z,1116061.457 [CTD_Seabird] Communications Fault, FailCount= 3 1970-01-13T22:01:01.457Z,1116061.457 [CTD_Seabird](ERROR): Communications Fault 1970-01-13T22:01:01.585Z,1116061.585 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 1970-01-13T22:01:01.586Z,1116061.586 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 1970-01-13T22:01:01.861Z,1116061.861 [CTD_Seabird](INFO): Powering down 1970-01-13T22:01:06.822Z,1116066.822 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:01:06.822Z,1116066.822 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 1970-01-13T22:01:06.822Z,1116066.822 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 1970-01-13T22:01:06.824Z,1116066.824 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:01:06.824Z,1116066.824 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 1970-01-13T22:01:06.824Z,1116066.824 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 1970-01-13T22:01:06.826Z,1116066.826 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:01:06.826Z,1116066.826 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 1970-01-13T22:01:06.826Z,1116066.826 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 1970-01-13T22:01:06.838Z,1116066.838 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 1970-01-13T22:01:06.838Z,1116066.838 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 1970-01-13T22:01:06.838Z,1116066.838 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 1970-01-13T22:01:07.251Z,1116067.251 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 1970-01-13T22:01:07.252Z,1116067.252 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 1970-01-13T22:01:07.252Z,1116067.252 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 1970-01-13T22:01:07.252Z,1116067.252 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 1970-01-13T22:01:07.253Z,1116067.253 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 1970-01-13T22:01:07.253Z,1116067.253 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 1970-01-13T22:01:07.635Z,1116067.635 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 1970-01-13T22:01:12.083Z,1116072.083 [Startup:StartupSatComms:B](INFO): Timed out from 1970-01-13T22:00:11.7Z 1970-01-13T22:01:12.084Z,1116072.084 [Startup:StartupSatComms:B] Stopped 1970-01-13T22:01:12.084Z,1116072.084 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 1970-01-13T22:01:12.084Z,1116072.084 [Startup:StartupSatComms] Stopped 1970-01-13T22:01:12.084Z,1116072.084 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 1970-01-13T22:01:12.085Z,1116072.085 [Startup](INFO): Completed Startup 1970-01-13T22:01:12.085Z,1116072.085 [MissionManager](INFO): Startup is completed. 1970-01-13T22:01:12.085Z,1116072.085 [MissionManager](INFO): Uninitializing Mission Startup 1970-01-13T22:01:12.085Z,1116072.085 [Startup] Stopped 1970-01-13T22:01:12.085Z,1116072.085 [Startup](DEBUG): Aggregate::uninitialize Startup 1970-01-13T22:01:12.085Z,1116072.085 [Startup:A.GoToSurface] Stopped 1970-01-13T22:01:12.085Z,1116072.085 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 1970-01-13T22:01:12.494Z,1116072.494 [MissionManager](IMPORTANT): Started mission Default 1970-01-13T22:01:12.494Z,1116072.494 [Default] Running Loop=1 1970-01-13T22:01:12.495Z,1116072.495 [Default](DEBUG): Aggregate::initialize Default 1970-01-13T22:01:12.495Z,1116072.495 [Default:B.GoToSurface] Running Loop=1 1970-01-13T22:01:12.495Z,1116072.495 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 1970-01-13T22:01:12.495Z,1116072.495 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 1970-01-13T22:01:12.495Z,1116072.495 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 1970-01-13T22:01:12.496Z,1116072.496 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 1970-01-13T22:01:12.496Z,1116072.496 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 1970-01-13T22:01:12.496Z,1116072.496 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 1970-01-13T22:01:12.497Z,1116072.497 [Default:A.Wait] Running Loop=1 1970-01-13T22:01:12.497Z,1116072.497 [Default:A.Wait](DEBUG): Initialize Wait Component. 1970-01-13T22:01:16.153Z,1116076.153 [DAT](FAULT): failed to initialize, no bytes available on serial interface 1970-01-13T22:01:16.153Z,1116076.153 [DAT] Communications Fault, FailCount= 4 1970-01-13T22:01:16.153Z,1116076.153 [DAT](ERROR): Communications Fault 1970-01-13T22:01:16.523Z,1116076.523 [CBIT](ERROR): Communications Fault in component: DAT 1970-01-13T22:01:16.557Z,1116076.557 [DAT](INFO): Powering down 1970-01-13T22:01:17.386Z,1116077.386 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 1970-01-13T22:01:17.750Z,1116077.750 [CBIT](INFO): Clearing failed state for component DAT 1970-01-13T22:01:17.750Z,1116077.750 [DAT] No Fault, FailCount= 4 1970-01-13T22:01:19.605Z,1116079.605 [DAT](INFO): Powering up 1970-01-13T22:01:19.605Z,1116079.605 [DAT](DEBUG): Initializing DAT. 1970-01-13T22:01:25.803Z,1116085.803 [Default:A.Wait](INFO): Done Waiting. 1970-01-13T22:01:25.803Z,1116085.803 [Default:A.Wait] Stopped 1970-01-13T22:01:25.803Z,1116085.803 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 1970-01-13T22:01:26.206Z,1116086.206 [Default:CheckIn] Running Loop=1 1970-01-13T22:01:26.206Z,1116086.206 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 1970-01-13T22:01:26.206Z,1116086.206 [Default:CheckIn:Read_GPS] Running Loop=1 1970-01-13T22:01:26.611Z,1116086.611 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 1970-01-13T22:01:44.468Z,1116104.468 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction -15 degree 1970-01-13T22:02:00.627Z,1116120.627 [CommandExec](IMPORTANT): got command maintain control VerticalControl.elevatorAngleAction 15 degree 1970-01-13T22:02:19.841Z,1116139.841 [DAT](FAULT): failed to initialize, no bytes available on serial interface 1970-01-13T22:02:19.841Z,1116139.841 [DAT] Communications Fault, FailCount= 5 1970-01-13T22:02:19.841Z,1116139.841 [DAT](ERROR): Communications Fault 1970-01-13T22:02:20.013Z,1116140.013 [CBIT](ERROR): Communications Fault in component: DAT 1970-01-13T22:02:20.245Z,1116140.245 [DAT](INFO): Powering down 1970-01-13T22:02:21.230Z,1116141.230 [CBIT](INFO): Clearing failed state for component DAT 1970-01-13T22:02:21.230Z,1116141.230 [DAT] No Fault, FailCount= 5 1970-01-13T22:02:23.293Z,1116143.293 [DAT](INFO): Powering up 1970-01-13T22:02:23.293Z,1116143.293 [DAT](DEBUG): Initializing DAT. 1970-01-13T22:02:23.646Z,1116143.646 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T22:02:23.646Z,1116143.646 [MassServo] No Fault, FailCount= 5 1970-01-13T22:02:25.585Z,1116145.585 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T22:02:25.585Z,1116145.585 [MassServo] Communications Fault, FailCount= 1 1970-01-13T22:02:25.585Z,1116145.585 [MassServo](ERROR): Communications Fault 1970-01-13T22:02:25.590Z,1116145.590 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T22:02:25.721Z,1116145.721 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:02:25.722Z,1116145.722 [MassServo](INFO): Powering down 1970-01-13T22:02:26.489Z,1116146.489 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T22:02:26.609Z,1116146.609 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T22:02:26.614Z,1116146.614 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T22:02:26.615Z,1116146.615 [MassServo] No Fault, FailCount= 1 1970-01-13T22:02:29.217Z,1116149.217 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T22:02:29.217Z,1116149.217 [MassServo] Communications Fault, FailCount= 2 1970-01-13T22:02:29.217Z,1116149.217 [MassServo](ERROR): Communications Fault 1970-01-13T22:02:29.222Z,1116149.222 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T22:02:29.333Z,1116149.333 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:02:29.333Z,1116149.333 [MassServo](INFO): Powering down 1970-01-13T22:02:30.121Z,1116150.121 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T22:02:30.242Z,1116150.242 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T22:02:30.246Z,1116150.246 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T22:02:30.247Z,1116150.247 [MassServo] No Fault, FailCount= 2 1970-01-13T22:02:32.853Z,1116152.853 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T22:02:32.853Z,1116152.853 [MassServo] Communications Fault, FailCount= 3 1970-01-13T22:02:32.853Z,1116152.853 [MassServo](ERROR): Communications Fault 1970-01-13T22:02:32.858Z,1116152.858 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T22:02:32.965Z,1116152.965 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:02:32.966Z,1116152.966 [MassServo](INFO): Powering down 1970-01-13T22:02:33.765Z,1116153.765 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T22:02:33.886Z,1116153.886 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T22:02:33.891Z,1116153.891 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T22:02:33.891Z,1116153.891 [MassServo] No Fault, FailCount= 3 1970-01-13T22:02:36.545Z,1116156.545 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T22:02:36.545Z,1116156.545 [MassServo] Communications Fault, FailCount= 4 1970-01-13T22:02:36.545Z,1116156.545 [MassServo](ERROR): Communications Fault 1970-01-13T22:02:36.550Z,1116156.550 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T22:02:36.681Z,1116156.681 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:02:36.681Z,1116156.681 [MassServo](INFO): Powering down 1970-01-13T22:02:37.454Z,1116157.454 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T22:02:37.574Z,1116157.574 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T22:02:37.579Z,1116157.579 [CBIT](INFO): Clearing failed state for component MassServo 1970-01-13T22:02:37.579Z,1116157.579 [MassServo] No Fault, FailCount= 4 1970-01-13T22:02:40.173Z,1116160.173 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 1970-01-13T22:02:40.173Z,1116160.173 [MassServo] Communications Fault, FailCount= 5 1970-01-13T22:02:40.173Z,1116160.173 [MassServo](ERROR): Communications Fault 1970-01-13T22:02:40.178Z,1116160.178 [CBIT](ERROR): Communications Fault in component: MassServo 1970-01-13T22:02:40.178Z,1116160.178 [CBIT](CRITICAL): Communications Fault in component: MassServo 1970-01-13T22:02:40.298Z,1116160.298 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:02:40.298Z,1116160.298 [MassServo](INFO): Powering down 1970-01-13T22:02:40.301Z,1116160.301 [CBIT](INFO): Critical error at 19700113T220240 1970-01-13T22:02:41.109Z,1116161.109 [MassServo](DEBUG): Initializing EZServoServo. 1970-01-13T22:02:41.230Z,1116161.230 [MassServo](DEBUG): Initializing MassServo. 1970-01-13T22:02:47.886Z,1116167.886 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 1970-01-13T22:02:50.849Z,1116170.849 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T22:02:50.849Z,1116170.849 [PNI_TCM] No Fault, FailCount= 5 1970-01-13T22:02:58.392Z,1116178.392 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T22:02:58.392Z,1116178.392 [PNI_TCM] Data Fault, FailCount= 1 1970-01-13T22:02:58.392Z,1116178.392 [PNI_TCM](ERROR): Data Fault 1970-01-13T22:02:58.408Z,1116178.408 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T22:02:58.796Z,1116178.796 [PNI_TCM](INFO): Powering down 1970-01-13T22:03:00.024Z,1116180.024 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T22:03:00.024Z,1116180.024 [PNI_TCM] No Fault, FailCount= 1 1970-01-13T22:03:05.635Z,1116185.635 [CommandExec](IMPORTANT): got command maintain clear 1970-01-13T22:03:05.699Z,1116185.699 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,PNI_TCM,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter, 1970-01-13T22:03:07.683Z,1116187.683 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T22:03:07.683Z,1116187.683 [PNI_TCM] Data Fault, FailCount= 2 1970-01-13T22:03:07.683Z,1116187.683 [PNI_TCM](ERROR): Data Fault 1970-01-13T22:03:07.688Z,1116187.688 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:03:07.688Z,1116187.688 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 1970-01-13T22:03:07.688Z,1116187.688 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 1970-01-13T22:03:07.690Z,1116187.690 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:03:07.690Z,1116187.690 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 1970-01-13T22:03:07.690Z,1116187.690 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 1970-01-13T22:03:07.692Z,1116187.692 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 1970-01-13T22:03:07.692Z,1116187.692 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3 1970-01-13T22:03:07.692Z,1116187.692 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 1970-01-13T22:03:07.738Z,1116187.738 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T22:03:07.738Z,1116187.738 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 1970-01-13T22:03:07.739Z,1116187.739 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 1970-01-13T22:03:07.739Z,1116187.739 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 1970-01-13T22:03:08.093Z,1116188.093 [PNI_TCM](INFO): Powering down 1970-01-13T22:03:08.235Z,1116188.235 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 1970-01-13T22:03:08.235Z,1116188.235 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 1970-01-13T22:03:08.236Z,1116188.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 1970-01-13T22:03:08.236Z,1116188.236 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 1970-01-13T22:03:08.236Z,1116188.236 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 1970-01-13T22:03:08.236Z,1116188.236 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3 1970-01-13T22:03:08.517Z,1116188.517 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 1970-01-13T22:03:09.338Z,1116189.338 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T22:03:09.338Z,1116189.338 [PNI_TCM] No Fault, FailCount= 2 1970-01-13T22:03:13.774Z,1116193.774 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL 1970-01-13T22:03:13.775Z,1116193.775 [WetLabsBB2FL] No Fault, FailCount= 5 1970-01-13T22:03:13.806Z,1116193.806 [WetLabsBB2FL](INFO): Powering up 1970-01-13T22:03:14.568Z,1116194.568 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 1970-01-13T22:03:14.568Z,1116194.568 [Aanderaa_O2] No Fault, FailCount= 3 1970-01-13T22:03:16.989Z,1116196.989 [PNI_TCM](FAULT): Failed to set parameters during initialization. 1970-01-13T22:03:16.990Z,1116196.990 [PNI_TCM] Data Fault, FailCount= 3 1970-01-13T22:03:16.990Z,1116196.990 [PNI_TCM](ERROR): Data Fault 1970-01-13T22:03:17.013Z,1116197.013 [CBIT](ERROR): Data Fault in component: PNI_TCM 1970-01-13T22:03:17.375Z,1116197.375 [PNI_TCM](INFO): Powering down 1970-01-13T22:03:17.921Z,1116197.921 [CommandExec](IMPORTANT): got command quit 1970-01-13T22:03:18.609Z,1116198.609 [CBIT](INFO): Clearing failed state for component PNI_TCM 1970-01-13T22:03:18.610Z,1116198.610 [PNI_TCM] No Fault, FailCount= 3 1970-01-13T22:03:18.925Z,1116198.925 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:18.925Z,1116198.925 [CommandExec](INFO): Uninitializing the command executive. 1970-01-13T22:03:18.925Z,1116198.925 [CommandExec](INFO): Uninitializing the command scheduler. 1970-01-13T22:03:18.925Z,1116198.925 [CommandExec ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:18.949Z,1116198.949 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 1970-01-13T22:03:18.949Z,1116198.949 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 1970-01-13T22:03:18.949Z,1116198.949 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:18.949Z,1116198.949 [NavChartDb](INFO): Join timeout helper Thread ID is 6758 1970-01-13T22:03:19.097Z,1116199.097 [WetLabsBB2FL] Communications Fault, FailCount= 1 1970-01-13T22:03:19.097Z,1116199.097 [WetLabsBB2FL](ERROR): Communications Fault 1970-01-13T22:03:19.257Z,1116199.257 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:19.257Z,1116199.257 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.275Z,1116199.275 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 1970-01-13T22:03:19.275Z,1116199.275 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.276Z,1116199.276 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6760 1970-01-13T22:03:19.436Z,1116199.436 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL 1970-01-13T22:03:19.597Z,1116199.597 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:03:19.598Z,1116199.598 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:19.598Z,1116199.598 [WetLabsBB2FL](INFO): Powering down 1970-01-13T22:03:19.599Z,1116199.599 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.613Z,1116199.613 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 1970-01-13T22:03:19.613Z,1116199.613 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.613Z,1116199.613 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6761 1970-01-13T22:03:19.869Z,1116199.869 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:19.869Z,1116199.869 [CTD_Seabird](INFO): Powering down 1970-01-13T22:03:19.881Z,1116199.881 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.901Z,1116199.901 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 1970-01-13T22:03:19.901Z,1116199.901 [DAT ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:19.901Z,1116199.901 [DAT](INFO): Join timeout helper Thread ID is 6762 1970-01-13T22:03:20.077Z,1116200.077 [DAT](INFO): Powering down 1970-01-13T22:03:20.149Z,1116200.149 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:20.150Z,1116200.150 [DAT](INFO): Powering down 1970-01-13T22:03:20.150Z,1116200.150 [DAT ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.153Z,1116200.153 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 1970-01-13T22:03:20.153Z,1116200.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.153Z,1116200.153 [Radio_Surface](INFO): Join timeout helper Thread ID is 6763 1970-01-13T22:03:20.381Z,1116200.381 [Radio_Surface](INFO): Powering down 1970-01-13T22:03:20.382Z,1116200.382 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:20.382Z,1116200.382 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.398Z,1116200.398 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 1970-01-13T22:03:20.398Z,1116200.398 [Onboard ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.398Z,1116200.398 [Onboard](INFO): Join timeout helper Thread ID is 6764 1970-01-13T22:03:20.913Z,1116200.913 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:20.913Z,1116200.913 [Onboard ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.929Z,1116200.929 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 1970-01-13T22:03:20.929Z,1116200.929 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:20.930Z,1116200.930 [DataOverHttps](INFO): Join timeout helper Thread ID is 6765 1970-01-13T22:03:21.205Z,1116201.205 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:21.205Z,1116201.205 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.213Z,1116201.213 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 1970-01-13T22:03:21.214Z,1116201.214 [logger ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.214Z,1116201.214 [logger](INFO): Join timeout helper Thread ID is 6766 1970-01-13T22:03:21.245Z,1116201.245 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:21.245Z,1116201.245 [logger ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.253Z,1116201.253 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 1970-01-13T22:03:21.253Z,1116201.253 [CommandLine ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.254Z,1116201.254 [CommandLine](INFO): Join timeout helper Thread ID is 6767 1970-01-13T22:03:21.273Z,1116201.273 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:21.273Z,1116201.273 [CommandLine ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.293Z,1116201.293 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 1970-01-13T22:03:21.293Z,1116201.293 [CommandExec ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.294Z,1116201.294 [CommandExec](INFO): Join timeout helper Thread ID is 6768 1970-01-13T22:03:21.295Z,1116201.295 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 1970-01-13T22:03:21.295Z,1116201.295 [controlThread ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.295Z,1116201.295 [controlThread](INFO): Join timeout helper Thread ID is 6769 1970-01-13T22:03:21.405Z,1116201.405 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 1970-01-13T22:03:21.405Z,1116201.405 [controlThread](DEBUG): Uninitializing ControlThread 1970-01-13T22:03:21.406Z,1116201.406 [NAL9602](INFO): Powering down 1970-01-13T22:03:21.407Z,1116201.407 [PNI_TCM](INFO): Powering down 1970-01-13T22:03:21.613Z,1116201.613 [Aanderaa_O2](INFO): Powering down 1970-01-13T22:03:21.615Z,1116201.615 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 1970-01-13T22:03:21.616Z,1116201.616 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 1970-01-13T22:03:21.617Z,1116201.617 [NavChart](DEBUG): Uninitialize NavChart Navigation. 1970-01-13T22:03:21.618Z,1116201.618 [MissionManager](INFO): Uninitializing Mission Default 1970-01-13T22:03:21.618Z,1116201.618 [Default] Stopped 1970-01-13T22:03:21.618Z,1116201.618 [Default](DEBUG): Aggregate::uninitialize Default 1970-01-13T22:03:21.618Z,1116201.618 [Default:B.GoToSurface] Stopped 1970-01-13T22:03:21.618Z,1116201.618 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 1970-01-13T22:03:21.618Z,1116201.618 [Default:CheckIn] Stopped 1970-01-13T22:03:21.618Z,1116201.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 1970-01-13T22:03:21.618Z,1116201.618 [Default:CheckIn:Read_GPS] Stopped 1970-01-13T22:03:21.621Z,1116201.621 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 1970-01-13T22:03:21.621Z,1116201.621 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 1970-01-13T22:03:21.622Z,1116201.622 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 1970-01-13T22:03:21.622Z,1116201.622 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 1970-01-13T22:03:21.622Z,1116201.622 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 1970-01-13T22:03:21.622Z,1116201.622 [BuoyancyServo](INFO): Powering down 1970-01-13T22:03:21.637Z,1116201.637 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 1970-01-13T22:03:21.637Z,1116201.637 [ElevatorServo](INFO): Powering down 1970-01-13T22:03:21.638Z,1116201.638 [MassServo](DEBUG): Uninitialize Mass Servo. 1970-01-13T22:03:21.638Z,1116201.638 [MassServo](INFO): Powering down 1970-01-13T22:03:21.639Z,1116201.639 [RudderServo](DEBUG): Uninitialize Rudder Servo. 1970-01-13T22:03:21.639Z,1116201.639 [RudderServo](INFO): Powering down 1970-01-13T22:03:21.640Z,1116201.640 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 1970-01-13T22:03:21.640Z,1116201.640 [ThrusterHE](INFO): Powering down 1970-01-13T22:03:21.641Z,1116201.641 [SBIT](DEBUG): Uninitialize SBIT Component. 1970-01-13T22:03:21.641Z,1116201.641 [IBIT](DEBUG): Uninitialize IBIT Component. 1970-01-13T22:03:21.641Z,1116201.641 [CBIT](DEBUG): Uninitialize CBIT Component. 1970-01-13T22:03:21.641Z,1116201.641 [CBIT](DEBUG): Powering off loads. 1970-01-13T22:03:21.653Z,1116201.653 [CBIT](DEBUG): Disabling WDT. 1970-01-13T22:03:21.665Z,1116201.665 [CBIT](DEBUG): Opening all GF detection circuits. 1970-01-13T22:03:21.665Z,1116201.665 [controlThread ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.678Z,1116201.678 [DAT ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.683Z,1116201.683 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.687Z,1116201.687 [Onboard ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.692Z,1116201.692 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.818Z,1116201.818 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.823Z,1116201.823 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:21.960Z,1116201.960 [NavChartDb ThreadHandler](INFO): Thread cancelled. 1970-01-13T22:03:22.018Z,1116202.018 [logger ThreadHandler](INFO): Thread cancelled.