2017-10-16T23:28:29.442Z,1508196509.442 [Supervisor](DEBUG): Initializing supervisor.
2017-10-16T23:28:29.445Z,1508196509.445 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-10-16T23:28:29.445Z,1508196509.445 [SyncHandler](INFO): Protected caller Thread ID is 2650
2017-10-16T23:28:29.445Z,1508196509.445 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-10-16T23:28:29.446Z,1508196509.446 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-10-16T23:28:29.447Z,1508196509.447 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2651
2017-10-16T23:28:29.450Z,1508196509.450 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-10-16T23:28:29.460Z,1508196509.460 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-10-16T23:28:29.461Z,1508196509.461 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-10-16T23:28:29.462Z,1508196509.462 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2652
2017-10-16T23:28:29.463Z,1508196509.463 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-10-16T23:28:29.464Z,1508196509.464 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-10-16T23:28:29.464Z,1508196509.464 [logger ThreadHandler](INFO): Protected caller Thread ID is 2653
2017-10-16T23:28:29.466Z,1508196509.466 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-10-16T23:28:29.466Z,1508196509.466 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-10-16T23:28:29.468Z,1508196509.468 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-10-16T23:28:29.657Z,1508196509.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-10-16T23:28:29.658Z,1508196509.658 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-10-16T23:28:29.772Z,1508196509.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-10-16T23:28:29.772Z,1508196509.772 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-10-16T23:28:29.965Z,1508196509.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-10-16T23:28:29.965Z,1508196509.965 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-10-16T23:28:30.234Z,1508196510.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-10-16T23:28:30.234Z,1508196510.234 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-10-16T23:28:30.638Z,1508196510.638 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-10-16T23:28:30.639Z,1508196510.639 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-10-16T23:28:31.096Z,1508196511.096 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-10-16T23:28:31.097Z,1508196511.097 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-10-16T23:28:31.430Z,1508196511.430 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-10-16T23:28:31.431Z,1508196511.431 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-10-16T23:28:31.991Z,1508196511.991 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-10-16T23:28:31.992Z,1508196511.992 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-10-16T23:28:32.088Z,1508196512.088 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-10-16T23:28:32.452Z,1508196512.452 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-10-16T23:28:32.453Z,1508196512.453 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-10-16T23:28:32.602Z,1508196512.602 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-10-16T23:28:32.603Z,1508196512.603 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-10-16T23:28:32.880Z,1508196512.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-10-16T23:28:32.881Z,1508196512.881 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-10-16T23:28:33.090Z,1508196513.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-10-16T23:28:33.091Z,1508196513.091 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-10-16T23:28:33.201Z,1508196513.201 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-10-16T23:28:33.201Z,1508196513.201 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-10-16T23:28:33.353Z,1508196513.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-10-16T23:28:33.355Z,1508196513.355 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2017-10-16T23:28:33.356Z,1508196513.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2017-10-16T23:28:33.599Z,1508196513.599 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-10-16T23:28:33.599Z,1508196513.599 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2017-10-16T23:28:33.693Z,1508196513.693 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2017-10-16T23:28:33.846Z,1508196513.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2017-10-16T23:28:34.017Z,1508196514.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2017-10-16T23:28:34.179Z,1508196514.179 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2017-10-16T23:28:34.363Z,1508196514.363 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2017-10-16T23:28:34.538Z,1508196514.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2017-10-16T23:28:34.648Z,1508196514.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2017-10-16T23:28:34.817Z,1508196514.817 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2017-10-16T23:28:34.936Z,1508196514.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2017-10-16T23:28:35.101Z,1508196515.101 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2017-10-16T23:28:35.102Z,1508196515.102 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-10-16T23:28:35.107Z,1508196515.107 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-10-16T23:28:35.230Z,1508196515.230 [SBIT](DEBUG): Construct Startup Built In Test.
2017-10-16T23:28:35.257Z,1508196515.257 [SBIT] Loaded
2017-10-16T23:28:35.258Z,1508196515.258 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-10-16T23:28:35.258Z,1508196515.258 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-10-16T23:28:35.283Z,1508196515.283 [IBIT] Loaded
2017-10-16T23:28:35.284Z,1508196515.284 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-10-16T23:28:35.287Z,1508196515.287 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-10-16T23:28:35.424Z,1508196515.424 [CBIT] Loaded
2017-10-16T23:28:35.424Z,1508196515.424 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-10-16T23:28:35.425Z,1508196515.425 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-10-16T23:28:35.428Z,1508196515.428 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-10-16T23:28:35.429Z,1508196515.429 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-10-16T23:28:35.436Z,1508196515.436 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-10-16T23:28:35.437Z,1508196515.437 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 406114E0
2017-10-16T23:28:35.437Z,1508196515.437 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2733
2017-10-16T23:28:35.450Z,1508196515.450 [Supervisor](INFO): Main Thread ID is 2649
2017-10-16T23:28:35.450Z,1508196515.450 [Supervisor](DEBUG): Running supervisor.
2017-10-16T23:28:35.451Z,1508196515.451 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2734
2017-10-16T23:28:35.454Z,1508196515.454 [controlThread ThreadHandler](INFO): Handler Thread ID is 2735
2017-10-16T23:28:35.454Z,1508196515.454 [controlThread](DEBUG): Initializing ControlThread
2017-10-16T23:28:35.455Z,1508196515.455 [SBIT](INFO): Initialize SBIT Component.
2017-10-16T23:28:35.456Z,1508196515.456 [SBIT](IMPORTANT): git: 2017-09-28-16-gfc4d8dc
2017-10-16T23:28:35.456Z,1508196515.456 [SBIT](INFO): git hash: fc4d8dc974620f9f70b7e6a619a5c2ec703a2a71
2017-10-16T23:28:35.456Z,1508196515.456 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2017-10-16T23:28:35.457Z,1508196515.457 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Tue Oct 3 09:53:58 PDT 2017
2017-10-16T23:28:35.458Z,1508196515.458 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2017-10-16T23:28:35.459Z,1508196515.459 [IBIT](INFO): Initialize IBIT Component.
2017-10-16T23:28:35.459Z,1508196515.459 [CBIT](DEBUG): Initialize CBIT Component.
2017-10-16T23:28:35.460Z,1508196515.460 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-10-16T23:28:35.461Z,1508196515.461 [logger ThreadHandler](INFO): Handler Thread ID is 2736
2017-10-16T23:28:35.463Z,1508196515.463 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2737
2017-10-16T23:28:35.467Z,1508196515.467 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-10-16T23:28:35.467Z,1508196515.467 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-10-16T23:28:35.468Z,1508196515.468 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-10-16T23:28:35.468Z,1508196515.468 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-10-16T23:28:35.479Z,1508196515.479 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-10-16T23:28:35.480Z,1508196515.480 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-10-16T23:28:35.481Z,1508196515.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-10-16T23:28:35.481Z,1508196515.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-10-16T23:28:35.481Z,1508196515.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-10-16T23:28:35.481Z,1508196515.481 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-10-16T23:28:35.481Z,1508196515.481 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-10-16T23:28:35.544Z,1508196515.544 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-10-16T23:28:35.554Z,1508196515.554 [MissionManager](CRITICAL): Unable to find MissionComponent with name GoToSurface at Startup:A
2017-10-16T23:28:35.554Z,1508196515.554 [Startup](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.558Z,1508196515.558 [MissionManager](DEBUG):
2017-10-16T23:28:35.559Z,1508196515.559 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-10-16T23:28:35.604Z,1508196515.604 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-10-16T23:28:35.605Z,1508196515.605 [MissionManager](CRITICAL): Unable to find MissionComponent with name Wait at Default:A
2017-10-16T23:28:35.605Z,1508196515.605 [Default](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.605Z,1508196515.605 [MissionManager](CRITICAL): Unable to find MissionComponent with name GoToSurface at Default:A
2017-10-16T23:28:35.606Z,1508196515.606 [Default](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.625Z,1508196515.625 [MissionManager](CRITICAL): Unable to find MissionComponent with name Execute at Default:CheckIn:Read_Iridium:A_Timeout:A
2017-10-16T23:28:35.626Z,1508196515.626 [Default:CheckIn:Read_Iridium:A_Timeout](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.627Z,1508196515.627 [MissionManager](CRITICAL): Unable to find MissionComponent with name Wait at Default:CheckIn:C
2017-10-16T23:28:35.627Z,1508196515.627 [Default:CheckIn](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.647Z,1508196515.647 [MissionManager](CRITICAL): Unable to find MissionComponent with name Execute at Default:C
2017-10-16T23:28:35.648Z,1508196515.648 [Default](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:28:35.650Z,1508196515.650 [MissionManager](DEBUG):
0
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
13
Burn 300
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2017-10-16T23:28:35.652Z,1508196515.652 [controlThread](DEBUG): Component order: CycleStarter,MissionManager,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-10-16T23:28:35.704Z,1508196515.704 [CommandLine](FAULT): Scheduling is paused
2017-10-16T23:28:35.704Z,1508196515.704 [CBIT](INFO): Critical error at 20171016T232835
2017-10-16T23:28:35.705Z,1508196515.705 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-10-16T23:29:21.184Z,1508196561.184 [CommandLine](IMPORTANT): got command show stack
2017-10-16T23:29:21.184Z,1508196561.184 [CommandLine](IMPORTANT): Behavior Stack:
2017-10-16T23:29:21.184Z,1508196561.184 [MissionManager](IMPORTANT): Mission loaded, but not running.
2017-10-16T23:29:38.859Z,1508196578.859 [SBIT](IMPORTANT): Beginning Startup BIT
2017-10-16T23:29:38.863Z,1508196578.863 [CBIT](IMPORTANT): Beginning ground fault scan
2017-10-16T23:29:49.265Z,1508196589.265 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005533
CHAN A1 (24V): 0.003372
CHAN A2 (12V): -0.005138
CHAN A3 (5V): -0.003036
CHAN B0 (3.3V): -0.000761
CHAN B1 (3.15aV): -0.001153
CHAN B2 (3.15bV): -0.000763
CHAN B3 (GND): -0.000003
OPEN: 0.004694
Full Scale Calc: 4.765 mA, -1.589 mA
2017-10-16T23:29:52.060Z,1508196592.060 [SBIT](ERROR): Could not read elevatorAngleReader_.
2017-10-16T23:29:52.060Z,1508196592.060 [SBIT](ERROR): Could not read rudderAngleReader_.
2017-10-16T23:29:52.060Z,1508196592.060 [SBIT](ERROR): Could not read massPosReader_.
2017-10-16T23:30:18.459Z,1508196618.459 [SBIT](ERROR): Could not read elevatorAngleReader_.
2017-10-16T23:30:18.459Z,1508196618.459 [SBIT](ERROR): Could not read rudderAngleReader_.
2017-10-16T23:30:18.460Z,1508196618.460 [SBIT](ERROR): Could not read massPosReader_.
2017-10-16T23:30:18.460Z,1508196618.460 [SBIT](FAULT): Control surface position failure.
2017-10-16T23:30:32.059Z,1508196632.059 [SBIT](ERROR): Could not read elevatorAngleReader_.
2017-10-16T23:30:32.059Z,1508196632.059 [SBIT](ERROR): Could not read rudderAngleReader_.
2017-10-16T23:30:32.059Z,1508196632.059 [SBIT](ERROR): Could not read massPosReader_.
2017-10-16T23:30:32.060Z,1508196632.060 [SBIT](FAULT): Control surface position failure.
2017-10-16T23:30:32.459Z,1508196632.459 [SBIT](CRITICAL): SBIT FAILED
2017-10-16T23:30:32.460Z,1508196632.460 [CBIT](INFO): Critical error at 20171016T233032
2017-10-16T23:30:32.859Z,1508196632.859 [MissionManager](IMPORTANT): Started mission Startup
2017-10-16T23:30:32.859Z,1508196632.859 [Startup] Running Loop=1
2017-10-16T23:30:32.859Z,1508196632.859 [Startup](DEBUG): Aggregate::initialize Startup
2017-10-16T23:30:32.860Z,1508196632.860 [Startup:StartupSatComms] Running Loop=1
2017-10-16T23:30:32.860Z,1508196632.860 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-10-16T23:30:32.860Z,1508196632.860 [Startup:StartupSatComms:A] Running Loop=1
2017-10-16T23:30:32.861Z,1508196632.861 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-10-16T23:30:36.124Z,1508196636.124 [CommandLine](IMPORTANT): got command show stack
2017-10-16T23:30:36.124Z,1508196636.124 [CommandLine](IMPORTANT): Behavior Stack:
2017-10-16T23:30:36.125Z,1508196636.125 [Startup:StartupSatComms](IMPORTANT): Priority 0: Startup:StartupSatComms:A
2017-10-16T23:31:33.259Z,1508196693.259 [Startup:StartupSatComms:A](INFO): Timed out from 2017-10-16T23:30:32.9Z
2017-10-16T23:31:33.259Z,1508196693.259 [Startup:StartupSatComms:A] Stopped
2017-10-16T23:31:33.259Z,1508196693.259 [Startup:StartupSatComms:B] Running Loop=1
2017-10-16T23:31:33.659Z,1508196693.659 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-10-16T23:32:13.524Z,1508196733.524 [CommandLine](IMPORTANT): got command show stack
2017-10-16T23:32:13.524Z,1508196733.524 [CommandLine](IMPORTANT): Behavior Stack:
2017-10-16T23:32:13.525Z,1508196733.525 [Startup:StartupSatComms](IMPORTANT): Priority 0: Startup:StartupSatComms:B
2017-10-16T23:32:33.659Z,1508196753.659 [Startup:StartupSatComms:B](INFO): Timed out from 2017-10-16T23:31:33.3Z
2017-10-16T23:32:33.659Z,1508196753.659 [Startup:StartupSatComms:B] Stopped
2017-10-16T23:32:33.659Z,1508196753.659 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-10-16T23:32:33.659Z,1508196753.659 [Startup:StartupSatComms] Stopped
2017-10-16T23:32:33.660Z,1508196753.660 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-10-16T23:32:33.660Z,1508196753.660 [Startup](INFO): Completed Startup
2017-10-16T23:32:33.660Z,1508196753.660 [MissionManager](INFO): Startup is completed.
2017-10-16T23:32:33.660Z,1508196753.660 [MissionManager](INFO): Uninitializing Mission Startup
2017-10-16T23:32:33.660Z,1508196753.660 [Startup] Stopped
2017-10-16T23:32:33.660Z,1508196753.660 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-10-16T23:32:34.059Z,1508196754.059 [MissionManager](IMPORTANT): Started mission Default
2017-10-16T23:32:34.059Z,1508196754.059 [Default] Running Loop=1
2017-10-16T23:32:34.059Z,1508196754.059 [Default](DEBUG): Aggregate::initialize Default
2017-10-16T23:32:34.059Z,1508196754.059 [Default:CheckIn] Running Loop=1
2017-10-16T23:32:34.059Z,1508196754.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-10-16T23:32:34.059Z,1508196754.059 [Default:CheckIn:Read_GPS] Running Loop=1
2017-10-16T23:32:34.060Z,1508196754.060 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-10-16T23:33:36.728Z,1508196816.728 [CommandLine](IMPORTANT): got command report mod WetLabsBB2FL.loadAtStartup
2017-10-16T23:33:36.863Z,1508196816.863 [Reporter](INFO): WetLabsBB2FL.loadAtStartup 1 bool
2017-10-16T23:33:51.864Z,1508196831.864 [CommandLine](IMPORTANT): got command report clear
2017-10-16T23:34:07.984Z,1508196847.984 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2017-10-16T23:34:07.985Z,1508196847.985 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2017-10-16T23:34:08.086Z,1508196848.086 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2017-10-16T23:34:08.089Z,1508196848.089 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2017-10-16T23:34:08.092Z,1508196848.092 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2017-10-16T23:34:08.095Z,1508196848.095 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2017-10-16T23:34:08.098Z,1508196848.098 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2017-10-16T23:34:08.101Z,1508196848.101 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2017-10-16T23:34:08.131Z,1508196848.131 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2017-10-16T23:34:08.140Z,1508196848.140 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2017-10-16T23:34:08.150Z,1508196848.150 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2017-10-16T23:34:08.152Z,1508196848.152 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2017-10-16T23:34:08.155Z,1508196848.155 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool
2017-10-16T23:34:08.158Z,1508196848.158 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 1.000000 bool
2017-10-16T23:34:08.163Z,1508196848.163 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 0.000000 bool
2017-10-16T23:34:08.166Z,1508196848.166 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2017-10-16T23:34:08.169Z,1508196848.169 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2017-10-16T23:34:08.190Z,1508196848.190 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2017-10-16T23:34:08.216Z,1508196848.216 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2017-10-16T23:34:08.219Z,1508196848.219 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2017-10-16T23:34:08.222Z,1508196848.222 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2017-10-16T23:34:08.224Z,1508196848.224 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2017-10-16T23:34:08.227Z,1508196848.227 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2017-10-16T23:34:08.242Z,1508196848.242 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2017-10-16T23:34:08.245Z,1508196848.245 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2017-10-16T23:34:08.248Z,1508196848.248 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2017-10-16T23:34:08.251Z,1508196848.251 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2017-10-16T23:34:08.266Z,1508196848.266 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2017-10-16T23:34:08.268Z,1508196848.268 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2017-10-16T23:34:08.271Z,1508196848.271 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2017-10-16T23:34:08.274Z,1508196848.274 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2017-10-16T23:34:08.304Z,1508196848.304 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2017-10-16T23:34:08.307Z,1508196848.307 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2017-10-16T23:34:08.310Z,1508196848.310 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan arcdeg
2017-10-16T23:34:08.353Z,1508196848.353 [MissionManager](CRITICAL): Unable to find MissionComponent with name PeakDetectVsDepth at Science:PeakDetectChl:A
2017-10-16T23:34:08.353Z,1508196848.353 [Science:PeakDetectChl](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:34:08.364Z,1508196848.364 [MissionManager](CRITICAL): Unable to find MissionComponent with name PeakDetectHorizontal at Science:HighestChlPeakReport:A
2017-10-16T23:34:08.364Z,1508196848.364 [Science:HighestChlPeakReport](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:34:08.366Z,1508196848.366 [MissionManager](CRITICAL): Unable to find MissionComponent with name PeakDetectVsDepth at Science:PeakDetectNO3:A
2017-10-16T23:34:08.366Z,1508196848.366 [Science:PeakDetectNO3](CRITICAL): Error!!!! no component for child behavior.
2017-10-16T23:34:08.390Z,1508196848.390 [MissionManager](DEBUG):
Set either this or PeakDetectNO3Active to True in order to sample
Nitrate. EnabledISUS must also be true.
Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
60.0
Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
-60.0
Turns on peak detection of Cholorphyll.
Turns on reporting of the highest peak value on yo-yo profiles in a
horizontal sliding window (of length numProfilesSlidingwindow)
If tracking a patch
Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
highest peak.
3
Length of horizontal sliding window. The highest yoyo-wise chl peak
(after low-pass filtering by a filter of length FilterWidthHorizontal)
within this window.
100
Turns on peak detection of Nitrate (and turns on ISUS).
Turns on seawater temperature derivative.
Automatically set to True if the Aanderaa O2 sensor is installed. Set to
false to disable reading Aandera O2.
Automatically set to true if the Neil Brown CTD is enabled. Set to false
to disable reading from the Neil Brown.
Automatically set to true if the CTD is enabled. Set to false to disable
reading from the CTD.
Automatically set to true if the NPS Turbulence sensor is enabled. Set
to false to disable reading from the NPS Turbulence sensor.
Automatically set to true if the the WetLabs BB2FL is enabled. Set to
false to disable the WetLabs BB2FL.
Automatically set to True if ISUS is enabled. Set to false to disable
ISUS.
Low-pass window length (based on depth sensor sampling interval 0.4
second) for low-pass filtering.
20
Shallow depth bound for detecting chl peak on each descent or ascent
profile.
NaN
Deep depth bound for detecting chl peak on each descent or ascent
profile.
NaN
0
0
NaN
NaN
0
0
NaN
NaN
0
0
NaN
NaN
NaN
2017-10-16T23:34:08.459Z,1508196848.459 [Default] Stopped
2017-10-16T23:34:08.459Z,1508196848.459 [Default](DEBUG): Aggregate::uninitialize Default
2017-10-16T23:34:08.459Z,1508196848.459 [Default:CheckIn] Stopped
2017-10-16T23:34:08.459Z,1508196848.459 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-10-16T23:34:08.459Z,1508196848.459 [Default:CheckIn:Read_GPS] Stopped
2017-10-16T23:34:08.459Z,1508196848.459 [MissionManager](IMPORTANT): Started mission Science
2017-10-16T23:34:08.459Z,1508196848.459 [Science] Running Loop=1
2017-10-16T23:34:08.459Z,1508196848.459 [Science](DEBUG): Aggregate::initialize Science
2017-10-16T23:34:08.460Z,1508196848.460 [Science:A] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:B] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:C] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:D] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:E] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:F] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:PeakDetectChl] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2017-10-16T23:34:08.460Z,1508196848.460 [Science:PeakDetectChl:A] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:HighestChlPeakReport] Running Loop=1
2017-10-16T23:34:08.460Z,1508196848.460 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2017-10-16T23:34:08.460Z,1508196848.460 [Science:PeakDetectNO3] Running Loop=1
2017-10-16T23:34:08.461Z,1508196848.461 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2017-10-16T23:34:08.461Z,1508196848.461 [Science:PeakDetectNO3:A] Running Loop=1
2017-10-16T23:34:08.461Z,1508196848.461 [Science:PeakDetectNO3] Stopped
2017-10-16T23:34:08.461Z,1508196848.461 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2017-10-16T23:34:08.461Z,1508196848.461 [Science:PeakDetectNO3:A] Stopped
2017-10-16T23:34:08.461Z,1508196848.461 [Science:HighestChlPeakReport] Stopped
2017-10-16T23:34:08.461Z,1508196848.461 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2017-10-16T23:34:08.462Z,1508196848.462 [Science:PeakDetectChl] Stopped
2017-10-16T23:34:08.462Z,1508196848.462 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2017-10-16T23:34:08.462Z,1508196848.462 [Science:PeakDetectChl:A] Stopped
2017-10-16T23:34:08.462Z,1508196848.462 [Science:F] Running Loop=1
2017-10-16T23:34:08.462Z,1508196848.462 [Science:F](CRITICAL): Unable to read non-existent data element: mass_concentration_of_chlorophyll_in_sea_water
2017-10-16T23:34:08.464Z,1508196848.464 [Science:E] Stopped
2017-10-16T23:34:08.464Z,1508196848.464 [Science:D] Stopped
2017-10-16T23:34:08.464Z,1508196848.464 [Science:C] Running Loop=1
2017-10-16T23:34:08.464Z,1508196848.464 [Science:C](CRITICAL): Unable to read non-existent data element: sea_water_temperature
2017-10-16T23:34:08.464Z,1508196848.464 [Science:C](CRITICAL): Unable to read non-existent data element: sea_water_salinity
2017-10-16T23:34:08.466Z,1508196848.466 [Science:B] Stopped
2017-10-16T23:34:08.466Z,1508196848.466 [Science:A] Running Loop=1
2017-10-16T23:34:08.466Z,1508196848.466 [Science:A](CRITICAL): Unable to read non-existent data element: mass_concentration_of_oxygen_in_sea_water
2017-10-16T23:34:08.469Z,1508196848.469 [CBIT](INFO): Critical error at 20171016T233408
2017-10-16T23:34:08.470Z,1508196848.470 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-10-16T23:34:08.518Z,1508196848.518 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2017-10-16T23:34:08.859Z,1508196848.859 [Science:F](CRITICAL): Unable to read non-existent data element: mass_concentration_of_chlorophyll_in_sea_water
2017-10-16T23:34:08.860Z,1508196848.860 [Science:C](CRITICAL): Unable to read non-existent data element: sea_water_temperature
2017-10-16T23:34:08.860Z,1508196848.860 [Science:C](CRITICAL): Unable to read non-existent data element: sea_water_salinity
2017-10-16T23:34:08.860Z,1508196848.860 [Science:A](CRITICAL): Unable to read non-existent data element: mass_concentration_of_oxygen_in_sea_water
2017-10-16T23:34:08.861Z,1508196848.861 [MissionManager](INFO): MissionManager is completed.
2017-10-16T23:34:08.861Z,1508196848.861 [MissionManager](INFO): Uninitializing Mission Science
2017-10-16T23:34:08.861Z,1508196848.861 [Science] Stopped
2017-10-16T23:34:08.861Z,1508196848.861 [Science](DEBUG): Aggregate::uninitialize Science
2017-10-16T23:34:08.861Z,1508196848.861 [Science:A] Stopped
2017-10-16T23:34:08.861Z,1508196848.861 [Science:C] Stopped
2017-10-16T23:34:08.861Z,1508196848.861 [Science:F] Stopped
2017-10-16T23:34:08.868Z,1508196848.868 [CBIT](INFO): Critical error at 20171016T233408
2017-10-16T23:34:09.259Z,1508196849.259 [MissionManager](IMPORTANT): Started mission Default
2017-10-16T23:34:09.259Z,1508196849.259 [Default] Running Loop=1
2017-10-16T23:34:09.259Z,1508196849.259 [Default](DEBUG): Aggregate::initialize Default
2017-10-16T23:34:09.259Z,1508196849.259 [Default:CheckIn] Running Loop=1
2017-10-16T23:34:09.259Z,1508196849.259 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-10-16T23:34:09.259Z,1508196849.259 [Default:CheckIn:Read_GPS] Running Loop=1
2017-10-16T23:34:21.048Z,1508196861.048 [CommandLine](IMPORTANT): got command quit
2017-10-16T23:34:22.066Z,1508196862.066 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-16T23:34:22.066Z,1508196862.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.067Z,1508196862.067 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2017-10-16T23:34:22.068Z,1508196862.068 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.068Z,1508196862.068 [CommandLine](INFO): Join timeout helper Thread ID is 2738
2017-10-16T23:34:22.079Z,1508196862.079 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-10-16T23:34:22.079Z,1508196862.079 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.080Z,1508196862.080 [NavChartDb](INFO): Join timeout helper Thread ID is 2739
2017-10-16T23:34:22.112Z,1508196862.112 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-16T23:34:22.112Z,1508196862.112 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.131Z,1508196862.131 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-10-16T23:34:22.132Z,1508196862.132 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.132Z,1508196862.132 [logger](INFO): Join timeout helper Thread ID is 2740
2017-10-16T23:34:22.168Z,1508196862.168 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-16T23:34:22.168Z,1508196862.168 [logger ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.171Z,1508196862.171 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-10-16T23:34:22.172Z,1508196862.172 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.172Z,1508196862.172 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-10-16T23:34:22.172Z,1508196862.172 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.172Z,1508196862.172 [controlThread](INFO): Join timeout helper Thread ID is 2741
2017-10-16T23:34:22.456Z,1508196862.456 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-10-16T23:34:22.456Z,1508196862.456 [controlThread](DEBUG): Uninitializing ControlThread
2017-10-16T23:34:22.456Z,1508196862.456 [MissionManager](INFO): Uninitializing Mission Default
2017-10-16T23:34:22.456Z,1508196862.456 [Default] Stopped
2017-10-16T23:34:22.456Z,1508196862.456 [Default](DEBUG): Aggregate::uninitialize Default
2017-10-16T23:34:22.457Z,1508196862.457 [Default:CheckIn] Stopped
2017-10-16T23:34:22.457Z,1508196862.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-10-16T23:34:22.457Z,1508196862.457 [Default:CheckIn:Read_GPS] Stopped
2017-10-16T23:34:22.458Z,1508196862.458 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-10-16T23:34:22.458Z,1508196862.458 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-10-16T23:34:22.459Z,1508196862.459 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-10-16T23:34:22.459Z,1508196862.459 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.480Z,1508196862.480 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-10-16T23:34:22.522Z,1508196862.522 [logger ThreadHandler](INFO): Thread cancelled.