1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 134 INFO NodeManager [main]: Starting NodeManager: $Name: $ 135 INFO NodeManager [main]: Node IP: foce/127.0.1.1 207 INFO NodeManager [main]: Node ID has been set to 1726 435 DEBUG PublisherComponent [main]: Default constructor called 448 DEBUG PublisherComponent [main]: setTopicname called with name topic/SSDSIngestTopic 448 DEBUG PublisherComponent [main]: topicname is now topic/SSDSIngestTopic 448 DEBUG PublisherComponent [main]: Not connected so calling start 449 DEBUG PublisherComponent [main]: PublisherComponent stop called 449 DEBUG PublisherComponent [main]: Everything should be closed now 449 DEBUG PublisherComponent [main]: start called, setting connected to true 449 DEBUG PublisherComponent [main]: Going to get jndiContext 649 DEBUG PublisherComponent [main]: JNDI environment = {java.naming.provider.url=bob:1099, java.naming.factory.initial=org.jnp.interfaces.NamingContextFactory, jnp.timeout=0, jnp.socketFactory=org.jnp.interfaces.TimedSocketFactory, java.naming.factory.url.pkgs=org.jboss.naming:org.jnp.interfaces, java.naming.factory.url.pkgs.Naming=org.jboss.naming, jnp.sotimeout=0} 667 DEBUG PublisherComponent [main]: Going to get the topic connection factory from the JNDI 1405 DEBUG PublisherComponent [main]: Going to create a topic connection from the factory 1753 DEBUG PublisherComponent [main]: Going to get the topic topic/SSDSIngestTopic from the JNDI lookup 1784 DEBUG PublisherComponent [main]: Going to create a topic session from the connection 1862 DEBUG PublisherComponent [main]: Now going to call start on the topic connection 1959 DEBUG PublisherComponent [main]: Connected was true after start 1959 DEBUG PublisherComponent [main]: Should have set the topicName to topic/SSDSIngestTopic 2446 INFO PortManager [main]: Found 23 configured ports in properties file 2976 INFO SleepManager [main]: SleepManager constructor 2977 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 2986 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 2990 INFO CpuLease [main]: CpuLease() constructor 3001 INFO CpuLease [Thread-7]: CpuLease started 4181 INFO NodeManager [main]: Starting registry... 4186 INFO NodeManager [main]: registry started. 4187 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 4318 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 4440 INFO PortManager [main]: Starting service... 7146 INFO BaseInstrumentService [main]: initializeInstrument() for 7147 INFO BaseInstrumentService [main]: done with initializeInstrument() for 7567 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 7570 INFO PortManager [main]: service started 7922 INFO PortManager [main]: Starting service... 10214 INFO BaseInstrumentService [main]: initializeInstrument() for 10216 INFO BaseInstrumentService [main]: done with initializeInstrument() for 10631 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 10632 INFO PortManager [main]: service started 10688 INFO PortManager [main]: Starting service... 13070 INFO BaseInstrumentService [main]: initializeInstrument() for 13071 INFO BaseInstrumentService [main]: done with initializeInstrument() for 13232 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 13233 INFO PortManager [main]: service started 13264 INFO PortManager [main]: Starting service... 14094 INFO BaseInstrumentService [main]: initializeInstrument() for 14095 INFO BaseInstrumentService [main]: done with initializeInstrument() for 14240 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 14240 INFO PortManager [main]: service started 14460 INFO PortManager [main]: Starting service... 19159 INFO BaseInstrumentService [main]: initializeInstrument() for 19159 INFO SBE52MP [main]: initializeInstrument() - Getting SBE's attention (Stops any previous logging). 23684 INFO BaseInstrumentService [main]: done with initializeInstrument() for 26969 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 26969 INFO PortManager [main]: service started 27015 INFO PortManager [main]: Starting service... 30880 INFO BaseInstrumentService [main]: initializeInstrument() for 30880 INFO SBE52MP [main]: initializeInstrument() - Getting SBE's attention (Stops any previous logging). 35417 INFO BaseInstrumentService [main]: done with initializeInstrument() for 38678 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 38679 INFO PortManager [main]: service started 38789 INFO PortManager [main]: Starting service... 41362 INFO BaseInstrumentService [main]: initializeInstrument() for 42251 INFO Vector [main]: initializeInstrument() - Getting Aquadopp's attention (Stops any previous logging). 49386 INFO BaseInstrumentService [main]: done with initializeInstrument() for 49420 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 56528 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 56529 INFO PortManager [main]: service started 56569 INFO PortManager [main]: Starting service... 61287 INFO BaseInstrumentService [main]: initializeInstrument() for 61288 INFO BaseInstrumentService [main]: done with initializeInstrument() for 61437 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 61437 INFO PortManager [main]: service started 61499 INFO PortManager [main]: Starting service... 63662 ERROR RegistryEntry [Thread-48]: Error parsing packet: java.text.ParseException: Not a Parseable Packet type 64191 ERROR StreamingInstrumentService [Thread-51]: acquire(): 256:port Power on 64192 ERROR StreamingInstrumentService [Thread-51]: 256:port Power on 66248 INFO BaseInstrumentService [main]: initializeInstrument() for 66249 INFO BaseInstrumentService [main]: done with initializeInstrument() for 66469 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 66470 INFO PortManager [main]: service started 66526 INFO PortManager [main]: Starting service... 71269 INFO BaseInstrumentService [main]: initializeInstrument() for 71269 INFO BaseInstrumentService [main]: done with initializeInstrument() for 71430 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 71431 INFO PortManager [main]: service started 71478 INFO PortManager [main]: Starting service... 76213 INFO BaseInstrumentService [main]: initializeInstrument() for 76214 INFO BaseInstrumentService [main]: done with initializeInstrument() for 76390 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 76391 INFO PortManager [main]: service started 76432 INFO PortManager [main]: Starting service... 81614 INFO BaseInstrumentService [main]: initializeInstrument() for 81614 INFO BaseInstrumentService [main]: done with initializeInstrument() for 81771 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 81791 INFO PortManager [main]: service started 81825 INFO PortManager [main]: Starting service... 86563 INFO BaseInstrumentService [main]: initializeInstrument() for 86563 INFO BaseInstrumentService [main]: done with initializeInstrument() for 86724 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 86725 INFO PortManager [main]: service started 86801 INFO PortManager [main]: Starting service... 89856 INFO BaseInstrumentService [main]: initializeInstrument() for 91972 ERROR Elmo [main]: org.mbari.siam.distributed.TimeoutException: readUntil() Timed out after 2016 ms outbuf.len=32 avail: 0 read: 0 92159 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92324 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 92325 INFO PortManager [main]: service started 92402 INFO PortManager [main]: Starting service... 95495 INFO BaseInstrumentService [main]: initializeInstrument() for 97631 ERROR Elmo [main]: org.mbari.siam.distributed.TimeoutException: readUntil() Timed out after 2023 ms outbuf.len=32 avail: 0 read: 0 97772 INFO BaseInstrumentService [main]: done with initializeInstrument() for 97921 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 97922 INFO PortManager [main]: service started 97978 INFO PortManager [main]: Starting service... 99428 INFO BaseInstrumentService [main]: initializeInstrument() for 102328 INFO BaseInstrumentService [main]: done with initializeInstrument() for 102538 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 102543 INFO PortManager [main]: service started 102614 INFO PortManager [main]: Starting service... 114501 INFO BaseInstrumentService [main]: initializeInstrument() for 114502 INFO WorkhorseADCP [main]: Initializing Workhorse... 116471 INFO WorkhorseADCP [main]: initializeInstrument() - Setting Workhorse to NOT log internally. 116974 INFO WorkhorseADCP [main]: initializeInstrument() - Setting Workhorse Coordinate system to 01010 117479 INFO WorkhorseADCP [main]: initializeInstrument() - Setting Workhorse normal sample interval (TE). 118094 INFO WorkhorseADCP [main]: initializeInstrument() - Setting Workhorse normal Pings Per EnSemble (WP). 118430 INFO WorkhorseADCP [main]: initializeInstrument() - Setting Workhorse normal Time Per Ping (TP). 118990 INFO WorkhorseADCP [main]: initializeInstrument() - Saving Workhorse Parameters. 121471 INFO WorkhorseADCP [main]: Initializing completed 121471 INFO BaseInstrumentService [main]: done with initializeInstrument() for 140176 ERROR WorkhorseADCP [main]: getInstrumentMetadata: Rescheduling service for 0 seconds... 140177 ERROR WorkhorseADCP [main]: getInstrumentMetadata: invalid _initialInterval 0 140366 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 140366 INFO PortManager [main]: service started 140379 INFO PortManager [main]: Service jar not specified for port /dev/ttyS7 140393 INFO PortManager [main]: Starting service... 141049 INFO BaseInstrumentService [main]: initializeInstrument() for 141050 INFO BaseInstrumentService [main]: done with initializeInstrument() for 141182 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 141183 INFO PortManager [main]: service started 145534 INFO PortManager [main]: Starting service... 151938 INFO BaseInstrumentService [main]: initializeInstrument() for 151938 INFO BaseInstrumentService [main]: done with initializeInstrument() for 152082 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 152084 INFO PortManager [main]: service started 156479 INFO PortManager [main]: Starting service... 161259 INFO BaseInstrumentService [main]: initializeInstrument() for 165219 INFO BaseInstrumentService [main]: done with initializeInstrument() for 165357 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 165357 INFO PortManager [main]: service started 169681 INFO PortManager [main]: Starting service... 172512 INFO BaseInstrumentService [main]: initializeInstrument() for 172720 INFO BaseInstrumentService [main]: done with initializeInstrument() for 172857 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 172858 INFO PortManager [main]: service started 172978 INFO PortManager [main]: Starting service... 178652 INFO BaseInstrumentService [main]: initializeInstrument() for 178823 DEBUG PH_Responder [main]: PH_Responder: called super 178823 DEBUG PH_Responder [main]: PH_Responder: setting record header 178823 DEBUG PH_Responder [main]: PH_Responder: setting number format 178824 DEBUG PH_Responder [main]: PH_Responder: initializing 178824 DEBUG PH_Responder [main]: PH_Responder.initialize: called super.initialize 178824 DEBUG PH_Responder [main]: PH_Responder.initialize: creating PH_ResponseParser 178835 DEBUG PH_Responder [main]: PH_Responder.initialize: setting up buffers and packets 178835 DEBUG PH_Responder [main]: PH_Responder.initialize: done 178890 DEBUG FOCEProcess [main]: registry2osdt using nodeURL rmi://localhost/node 178893 DEBUG FOCEProcess [main]: getNodeReference setting SocketFactory using host localhost 178895 INFO FOCEProcess [main]: SocketException:factory already defined 178895 DEBUG FOCEProcess [main]: Get node proxy from rmi://localhost/node 179344 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH1/pH 179345 DEBUG FOCEProcess [main]: creating connector type [1] 179345 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179552 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179554 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1724 179595 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH2/pH 179595 DEBUG FOCEProcess [main]: creating connector type [1] 179596 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179624 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179626 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1735 179631 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH3/pH 179631 DEBUG FOCEProcess [main]: creating connector type [1] 179631 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179664 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179666 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1722 179671 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH4/pH 179671 DEBUG FOCEProcess [main]: creating connector type [1] 179671 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179699 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179701 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1749 179706 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH5/pH 179706 DEBUG FOCEProcess [main]: creating connector type [1] 179706 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179739 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179740 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1734 179745 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH6/pH 179746 DEBUG FOCEProcess [main]: creating connector type [1] 179746 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179778 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179780 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1751 179787 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for pH7/pH 179787 DEBUG FOCEProcess [main]: creating connector type [1] 179788 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179850 DEBUG FOCEProcess [main]: serviceName - MBARI digital pH Sensor 179852 DEBUG FOCEProcess [main]: osdtName.mod - MBARI_digital_pH_Sensor-1753 179859 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for Velocity/avgVelocityX 179860 DEBUG FOCEProcess [main]: creating connector type [1] 179860 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179975 DEBUG FOCEProcess [main]: serviceName - Vector Velocimeter 179977 DEBUG FOCEProcess [main]: osdtName.mod - Vector_Velocimeter-1644 179984 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for Velocity/avgVelocityY 179984 DEBUG FOCEProcess [main]: creating connector type [1] 179984 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 179991 DEBUG FOCEProcess [main]: serviceName - Vector Velocimeter 179992 DEBUG FOCEProcess [main]: osdtName.mod - Vector_Velocimeter-1644 179996 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for ADCP/VelocityX 179997 DEBUG FOCEProcess [main]: creating connector type [1] 179998 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180304 DEBUG FOCEProcess [main]: serviceName - WH Sentinel ADCP 600kHz 180306 DEBUG FOCEProcess [main]: osdtName.mod - WH_Sentinel_ADCP_600kHz-1645 180311 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for ADCP/VelocityY 180311 DEBUG FOCEProcess [main]: creating connector type [1] 180311 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180318 DEBUG FOCEProcess [main]: serviceName - WH Sentinel ADCP 600kHz 180319 DEBUG FOCEProcess [main]: osdtName.mod - WH_Sentinel_ADCP_600kHz-1645 180323 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for motor2/encoderVelocity 180324 DEBUG FOCEProcess [main]: creating connector type [1] 180324 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180536 DEBUG FOCEProcess [main]: serviceName - Elmo Thruster Controller 180538 DEBUG FOCEProcess [main]: osdtName.mod - Elmo_Thruster_Controller-1729 180544 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for motor1/encoderVelocity 180544 DEBUG FOCEProcess [main]: creating connector type [1] 180544 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180582 DEBUG FOCEProcess [main]: serviceName - Elmo Thruster Controller 180584 DEBUG FOCEProcess [main]: osdtName.mod - Elmo_Thruster_Controller-1728 180590 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for ValveService/position0 180590 DEBUG FOCEProcess [main]: creating connector type [1] 180592 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180703 DEBUG FOCEProcess [main]: serviceName - Valve Controller 180706 DEBUG FOCEProcess [main]: osdtName.mod - Valve_Controller-1788 180706 ERROR FOCEProcess [main]: createConnector error [ siamHost localhost registry_key ValveService] 180710 ERROR FOCEProcess [main]: java.lang.Exception: Could not create connector (createConnector returned null) 180710 DEBUG FOCEProcess [main]: FOCEProcess - creating input connector for ValveService/position1 180710 DEBUG FOCEProcess [main]: creating connector type [1] 180711 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180718 DEBUG FOCEProcess [main]: serviceName - Valve Controller 180720 DEBUG FOCEProcess [main]: osdtName.mod - Valve_Controller-1788 180720 ERROR FOCEProcess [main]: createConnector error [ siamHost localhost registry_key ValveService] 180724 ERROR FOCEProcess [main]: java.lang.Exception: Could not create connector (createConnector returned null) 180725 DEBUG FOCEProcess [main]: FOCEProcess - creating output connector for motor2/encoderVelocity 180726 DEBUG FOCEProcess [main]: creating output type [1] 180726 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180744 DEBUG FOCEProcess [main]: FOCEProcess - creating output connector for motor1/encoderVelocity 180746 DEBUG FOCEProcess [main]: creating output type [1] 180747 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180753 DEBUG FOCEProcess [main]: FOCEProcess - creating output connector for eswPump/encoderVelocity 180754 DEBUG FOCEProcess [main]: creating output type [1] 180754 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180778 DEBUG FOCEProcess [main]: FOCEProcess - creating output connector for ValveService/position0 180779 DEBUG FOCEProcess [main]: creating output type [1] 180779 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180840 DEBUG FOCEProcess [main]: FOCEProcess - creating output connector for ValveService/position1 180841 DEBUG FOCEProcess [main]: creating output type [1] 180841 DEBUG FOCEProcess [main]: Have Node Proxy, requesting service 180908 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 180911 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 180912 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 180994 DEBUG PH_Responder [pH loop]: getting direction 180994 DEBUG PH_Responder [pH loop]: getting direction is [0.000] 180994 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 181300 INFO BaseInstrumentService [main]: done with initializeInstrument() for 182755 INFO PortManager [main]: service started 182761 INFO NodeManager [Thread-2]: serviceInstalled(): invoke remote callbacks 182892 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:41:30 PM 182908 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 183095 INFO NodeManager [main]: NodeManager "node" started. 190912 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 190913 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 190913 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 190940 DEBUG PH_Responder [pH loop]: getting direction 190941 DEBUG PH_Responder [pH loop]: getting direction is [0.000] 190941 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 192488 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8214] from OSDT data stream (via range validator) 200916 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 200917 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 200917 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 200924 DEBUG PH_Responder [pH loop]: getting direction 200924 DEBUG PH_Responder [pH loop]: getting direction is [67.47725] 200924 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 202494 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.821] from OSDT data stream (via range validator) 210921 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 210922 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 210922 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 210932 DEBUG PH_Responder [pH loop]: getting direction 210933 DEBUG PH_Responder [pH loop]: getting direction is [67.47725] 210933 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 212503 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 221045 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 221045 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 221046 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 221052 DEBUG PH_Responder [pH loop]: getting direction 221053 DEBUG PH_Responder [pH loop]: getting direction is [12.62926] 221053 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 222527 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 231051 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 231052 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 231052 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 231057 DEBUG PH_Responder [pH loop]: getting direction 231058 DEBUG PH_Responder [pH loop]: getting direction is [12.62926] 231058 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 232512 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 241057 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 241057 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 241057 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 241063 DEBUG PH_Responder [pH loop]: getting direction 241063 DEBUG PH_Responder [pH loop]: getting direction is [40.69223] 241063 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 242679 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 251062 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 251062 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 251062 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 251068 DEBUG PH_Responder [pH loop]: getting direction 251068 DEBUG PH_Responder [pH loop]: getting direction is [40.69223] 251068 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 252884 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 261066 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 261067 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 261067 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 261073 DEBUG PH_Responder [pH loop]: getting direction 261073 DEBUG PH_Responder [pH loop]: getting direction is [33.25108] 261079 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 262687 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 271074 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 271076 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 271076 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 271084 DEBUG PH_Responder [pH loop]: getting direction 271085 DEBUG PH_Responder [pH loop]: getting direction is [33.25108] 271085 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 272742 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8219] from OSDT data stream (via range validator) 281077 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 281077 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 281077 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 281086 DEBUG PH_Responder [pH loop]: getting direction 281086 DEBUG PH_Responder [pH loop]: getting direction is [46.70209] 281087 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 282693 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8218] from OSDT data stream (via range validator) 291081 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 291082 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 291082 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 291088 DEBUG PH_Responder [pH loop]: getting direction 291089 DEBUG PH_Responder [pH loop]: getting direction is [46.70209] 291089 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 292708 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 301086 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 301087 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 301087 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 301093 DEBUG PH_Responder [pH loop]: getting direction 301093 DEBUG PH_Responder [pH loop]: getting direction is [5.37164] 301093 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 302712 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 303353 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:43:31 PM 303354 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 311092 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 311092 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 311093 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 311098 DEBUG PH_Responder [pH loop]: getting direction 311099 DEBUG PH_Responder [pH loop]: getting direction is [5.37164] 311099 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 312716 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 321097 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 321097 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 321097 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 321103 DEBUG PH_Responder [pH loop]: getting direction 321104 DEBUG PH_Responder [pH loop]: getting direction is [-9.52829] 321104 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 322722 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 331102 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 331102 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 331102 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 331108 DEBUG PH_Responder [pH loop]: getting direction 331109 DEBUG PH_Responder [pH loop]: getting direction is [-9.52829] 331109 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 332734 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 341107 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 341107 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 341107 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 341128 DEBUG PH_Responder [pH loop]: getting direction 341129 DEBUG PH_Responder [pH loop]: getting direction is [-27.33585] 341129 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 342740 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8243] from OSDT data stream (via range validator) 351112 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 351113 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 351113 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 351118 DEBUG PH_Responder [pH loop]: getting direction 351119 DEBUG PH_Responder [pH loop]: getting direction is [-27.33585] 351119 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 352758 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 361117 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 361118 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 361118 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 361123 DEBUG PH_Responder [pH loop]: getting direction 361124 DEBUG PH_Responder [pH loop]: getting direction is [-48.72728] 361124 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 362750 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 363975 ERROR DataStructure [Thread-51]: Timed out while searching for sync for 1000 msec 363976 ERROR StreamingInstrumentService [Thread-51]: acquire(): Timed out in read():port Power on 363976 ERROR StreamingInstrumentService [Thread-51]: Timed out in read():port Power on 371118 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 371119 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 371119 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 371124 DEBUG PH_Responder [pH loop]: getting direction 371125 DEBUG PH_Responder [pH loop]: getting direction is [-48.72728] 371125 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 372754 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 381119 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 381120 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 381120 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 381142 DEBUG PH_Responder [pH loop]: getting direction 381142 DEBUG PH_Responder [pH loop]: getting direction is [-38.4369] 381143 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 382759 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 391125 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 391125 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 391128 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 391134 DEBUG PH_Responder [pH loop]: getting direction 391135 DEBUG PH_Responder [pH loop]: getting direction is [-38.4369] 391135 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 392764 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 401126 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 401126 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 401126 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 401132 DEBUG PH_Responder [pH loop]: getting direction 401132 DEBUG PH_Responder [pH loop]: getting direction is [-36.70616] 401132 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 402772 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 411131 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 411131 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 411131 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 411137 DEBUG PH_Responder [pH loop]: getting direction 411137 DEBUG PH_Responder [pH loop]: getting direction is [-36.70616] 411137 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 412783 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 421136 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 421136 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 421136 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 421142 DEBUG PH_Responder [pH loop]: getting direction 421142 DEBUG PH_Responder [pH loop]: getting direction is [-20.80964] 421143 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 422794 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 423365 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:45:31 PM 423365 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 431141 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 431142 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 431142 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 431147 DEBUG PH_Responder [pH loop]: getting direction 431148 DEBUG PH_Responder [pH loop]: getting direction is [-20.80964] 431148 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 432797 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 441146 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 441146 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 441147 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 441165 DEBUG PH_Responder [pH loop]: getting direction 441166 DEBUG PH_Responder [pH loop]: getting direction is [-21.99895] 441166 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 442802 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 451151 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 451152 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 451152 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 451157 DEBUG PH_Responder [pH loop]: getting direction 451158 DEBUG PH_Responder [pH loop]: getting direction is [-21.99895] 451158 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 452847 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8224] from OSDT data stream (via range validator) 461152 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 461153 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 461153 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 461158 DEBUG PH_Responder [pH loop]: getting direction 461159 DEBUG PH_Responder [pH loop]: getting direction is [13.49881] 461159 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 462824 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 471154 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 471154 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 471154 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 471159 DEBUG PH_Responder [pH loop]: getting direction 471160 DEBUG PH_Responder [pH loop]: getting direction is [13.49881] 471160 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 472833 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 481155 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 481155 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 481155 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 481161 DEBUG PH_Responder [pH loop]: getting direction 481161 DEBUG PH_Responder [pH loop]: getting direction is [19.51887] 481162 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 482854 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8219] from OSDT data stream (via range validator) 491160 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 491160 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 491160 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 491166 DEBUG PH_Responder [pH loop]: getting direction 491166 DEBUG PH_Responder [pH loop]: getting direction is [19.51887] 491167 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 492843 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 501165 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 501165 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 501165 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 501171 DEBUG PH_Responder [pH loop]: getting direction 501171 DEBUG PH_Responder [pH loop]: getting direction is [97.44173] 501171 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 502849 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 511170 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 511170 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 511171 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 511176 DEBUG PH_Responder [pH loop]: getting direction 511177 DEBUG PH_Responder [pH loop]: getting direction is [97.44173] 511177 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 512854 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8195] from OSDT data stream (via range validator) 521171 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 521172 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 521172 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 521177 DEBUG PH_Responder [pH loop]: getting direction 521178 DEBUG PH_Responder [pH loop]: getting direction is [46.41986] 521178 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 522877 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 531176 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 531177 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 531177 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 531182 DEBUG PH_Responder [pH loop]: getting direction 531182 DEBUG PH_Responder [pH loop]: getting direction is [46.41986] 531183 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 532868 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 541178 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 541178 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 541178 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 541183 DEBUG PH_Responder [pH loop]: getting direction 541183 DEBUG PH_Responder [pH loop]: getting direction is [55.65904] 541184 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 542873 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 543374 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:47:31 PM 543374 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 551182 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 551183 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 551183 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 551188 DEBUG PH_Responder [pH loop]: getting direction 551189 DEBUG PH_Responder [pH loop]: getting direction is [55.65904] 551189 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 552894 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 561184 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 561184 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 561184 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 561190 DEBUG PH_Responder [pH loop]: getting direction 561190 DEBUG PH_Responder [pH loop]: getting direction is [34.80741] 561190 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 562883 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 571185 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 571185 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 571185 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 571191 DEBUG PH_Responder [pH loop]: getting direction 571191 DEBUG PH_Responder [pH loop]: getting direction is [34.80741] 571191 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 572888 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 581190 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 581190 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 581190 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 581196 DEBUG PH_Responder [pH loop]: getting direction 581197 DEBUG PH_Responder [pH loop]: getting direction is [110.82502] 581197 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 582891 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 591196 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 591196 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 591196 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 591201 DEBUG PH_Responder [pH loop]: getting direction 591201 DEBUG PH_Responder [pH loop]: getting direction is [110.82502] 591202 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 592895 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8218] from OSDT data stream (via range validator) 601196 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 601196 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 601197 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 601202 DEBUG PH_Responder [pH loop]: getting direction 601202 DEBUG PH_Responder [pH loop]: getting direction is [138.56985] 601203 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 602904 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 611197 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 611198 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 611198 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 611203 DEBUG PH_Responder [pH loop]: getting direction 611203 DEBUG PH_Responder [pH loop]: getting direction is [138.56985] 611204 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 612909 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 621202 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 621203 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 621203 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 621208 DEBUG PH_Responder [pH loop]: getting direction 621209 DEBUG PH_Responder [pH loop]: getting direction is [115.07003] 621209 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 622914 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 631203 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 631204 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 631204 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 631210 DEBUG PH_Responder [pH loop]: getting direction 631210 DEBUG PH_Responder [pH loop]: getting direction is [115.07003] 631210 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 632920 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 641205 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 641205 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 641205 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 641210 DEBUG PH_Responder [pH loop]: getting direction 641211 DEBUG PH_Responder [pH loop]: getting direction is [76.40766] 641211 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 642931 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 651206 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 651212 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 651212 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 651217 DEBUG PH_Responder [pH loop]: getting direction 651218 DEBUG PH_Responder [pH loop]: getting direction is [76.40766] 651218 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 652933 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8226] from OSDT data stream (via range validator) 661211 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 661216 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 661216 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 661222 DEBUG PH_Responder [pH loop]: getting direction 661222 DEBUG PH_Responder [pH loop]: getting direction is [132.30891] 661222 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 662934 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 663383 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:49:31 PM 663384 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 671216 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 671216 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 671216 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 671222 DEBUG PH_Responder [pH loop]: getting direction 671223 DEBUG PH_Responder [pH loop]: getting direction is [132.30891] 671223 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 672940 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 681221 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 681221 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 681222 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 681227 DEBUG PH_Responder [pH loop]: getting direction 681228 DEBUG PH_Responder [pH loop]: getting direction is [163.0731] 681228 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 682940 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 691222 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 691222 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 691223 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 691228 DEBUG PH_Responder [pH loop]: getting direction 691228 DEBUG PH_Responder [pH loop]: getting direction is [163.0731] 691229 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 692942 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 701227 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 701228 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 701228 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 701233 DEBUG PH_Responder [pH loop]: getting direction 701234 DEBUG PH_Responder [pH loop]: getting direction is [163.39924] 701234 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 702943 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 711228 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 711229 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 711229 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 711234 DEBUG PH_Responder [pH loop]: getting direction 711234 DEBUG PH_Responder [pH loop]: getting direction is [163.39924] 711235 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 712944 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 721230 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 721230 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 721230 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 721235 DEBUG PH_Responder [pH loop]: getting direction 721236 DEBUG PH_Responder [pH loop]: getting direction is [44.54957] 721236 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 722948 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 731231 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 731231 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 731231 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 731237 DEBUG PH_Responder [pH loop]: getting direction 731237 DEBUG PH_Responder [pH loop]: getting direction is [44.54957] 731237 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 732950 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8219] from OSDT data stream (via range validator) 741232 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 741232 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 741232 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 741238 DEBUG PH_Responder [pH loop]: getting direction 741239 DEBUG PH_Responder [pH loop]: getting direction is [32.15779] 741239 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 742955 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 751237 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 751237 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 751237 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 751243 DEBUG PH_Responder [pH loop]: getting direction 751243 DEBUG PH_Responder [pH loop]: getting direction is [73.28771] 751243 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 752960 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8224] from OSDT data stream (via range validator) 761242 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 761243 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 761243 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 761248 DEBUG PH_Responder [pH loop]: getting direction 761249 DEBUG PH_Responder [pH loop]: getting direction is [40.40575] 761249 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 762961 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8226] from OSDT data stream (via range validator) 771244 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 771244 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 771244 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 771249 DEBUG PH_Responder [pH loop]: getting direction 771250 DEBUG PH_Responder [pH loop]: getting direction is [171.46107] 771250 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 772967 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 778145 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: removeService() 778146 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: Invoke service.shutdown() 779116 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: service.shutdown() complete 779117 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: unexportObject() succeeded for service on port ControlLoop 779146 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: removeService() - postEvent() 779147 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 779147 INFO NodeManager [Thread-2]: Done with serviceRemoved() 779148 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 779148 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 779150 INFO PortManager [RMI TCP Connection(18)-127.0.0.1]: removeService() - done 781245 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 781245 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 781246 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 781255 DEBUG PH_Responder [pH loop]: getting direction 781255 DEBUG PH_Responder [pH loop]: getting direction is [-156.30931] 781255 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 782972 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 783393 INFO CommsSchedulerTask [Thread-182]: CommsSchedulerTask.run(): Nov 11, 2011 2:51:31 PM 783393 INFO CommsLeaseListener [Thread-182]: leaseEstablishCallback() - Comms manager not enabled 789200 INFO NodeService [RMI TCP Connection(20)-127.0.0.1]: NodeService.scanPort(ControlLoop) 789296 INFO PortManager [RMI TCP Connection(20)-127.0.0.1]: Starting service... 791253 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 791254 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 791254 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 791259 DEBUG PH_Responder [pH loop]: getting direction 791260 DEBUG PH_Responder [pH loop]: getting direction is [-155.05102] 791260 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 792976 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 795150 INFO BaseInstrumentService [RMI TCP Connection(20)-127.0.0.1]: initializeInstrument() for 795232 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder: called super 795232 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder: setting record header 795232 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder: setting number format 795232 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder: initializing 795233 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder.initialize: called super.initialize 795233 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder.initialize: creating PH_ResponseParser 795243 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder.initialize: setting up buffers and packets 795243 DEBUG PH_Responder [RMI TCP Connection(20)-127.0.0.1]: PH_Responder.initialize: done 795264 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: registry2osdt using nodeURL rmi://localhost/node 795264 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: getNodeReference setting SocketFactory using host localhost 795265 INFO FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: SocketException:factory already defined 795265 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: Get node proxy from rmi://localhost/node 795301 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: FOCEProcess - creating input connector for pH1/pH 795301 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: creating connector type [1] 795302 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: Have Node Proxy, requesting service 795333 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: serviceName - MBARI digital pH Sensor 795336 DEBUG FOCEProcess [RMI TCP Connection(20)-127.0.0.1]: osdtName.mod - MBARI_digital_pH_Sensor-1724 801255 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 801256 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 801256 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 801261 DEBUG PH_Responder [pH loop]: getting direction 801261 DEBUG PH_Responder [pH loop]: getting direction is [-148.85427] 801262 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 802978 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 811264 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 811264 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 811264 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 811269 DEBUG PH_Responder [pH loop]: getting direction 811270 DEBUG PH_Responder [pH loop]: getting direction is [-22.62854] 811270 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 813014 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8221] from OSDT data stream (via range validator) 813314 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down services 813318 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'PMonitor' 813318 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 813318 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 813438 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 813438 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port PMonitor 813453 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 813454 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 813454 INFO NodeManager [Thread-2]: Done with serviceRemoved() 813454 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 813454 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 813455 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 813455 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'EMonitor' 813455 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 813455 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 814122 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 814123 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port EMonitor 814155 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 814155 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 814155 INFO NodeManager [Thread-2]: Done with serviceRemoved() 814155 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 814156 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 814157 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 814157 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'HMonitor' 814157 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 814158 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 814346 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 814346 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port HMonitor 815571 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 815571 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 815571 INFO NodeManager [Thread-2]: Done with serviceRemoved() 815571 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 815571 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 815573 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 815573 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'WMonitor' 815574 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 815574 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 815679 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 815680 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port WMonitor 815693 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 815693 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 815693 INFO NodeManager [Thread-2]: Done with serviceRemoved() 815694 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 815694 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 815694 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 815695 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS4' 815695 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 815695 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 815696 ERROR StopWatch [Thread-39]: wait(...) failed: java.lang.InterruptedException: sleep interrupted 821266 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 821266 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 821266 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 821271 DEBUG PH_Responder [pH loop]: getting direction 821272 DEBUG PH_Responder [pH loop]: getting direction is [-5.5412] 821272 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 823016 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8171] from OSDT data stream (via range validator) 831271 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 831271 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 831271 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 831276 DEBUG PH_Responder [pH loop]: getting direction 831277 DEBUG PH_Responder [pH loop]: getting direction is [-2.10739] 831277 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 833021 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8223] from OSDT data stream (via range validator) 841279 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 841279 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 841279 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 841285 DEBUG PH_Responder [pH loop]: getting direction 841285 DEBUG PH_Responder [pH loop]: getting direction is [-2.10739] 841285 ERROR PH_Responder [pH loop]: fwd valve:2 aft valve:0 843026 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 851284 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 851285 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 851285 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 851290 DEBUG PH_Responder [pH loop]: getting direction 851291 DEBUG PH_Responder [pH loop]: getting direction is [7.86334] 851291 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 853027 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 861286 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 861286 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 861287 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 861292 DEBUG PH_Responder [pH loop]: getting direction 861292 DEBUG PH_Responder [pH loop]: getting direction is [7.86334] 861292 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 863028 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 867885 WARN PolledInstrumentService [Thread-39]: Seabird SBE52MP.acquire(): org.mbari.siam.distributed.TimeoutException: readUntil() Timed out after 60011 ms outbuf.len=1000 avail: 0 read: 0 org.mbari.siam.distributed.TimeoutException: readUntil() Timed out after 60011 ms outbuf.len=1000 avail: 0 read: 0 at org.mbari.siam.utils.StreamUtils.readUntil(StreamUtils.java:115) at org.mbari.siam.core.BaseInstrumentService.readSample(BaseInstrumentService.java:1061) at org.mbari.siam.core.PolledInstrumentService.acquire(PolledInstrumentService.java:112) at org.mbari.siam.core.BaseInstrumentService.acquireSample(BaseInstrumentService.java:806) at org.mbari.siam.core.PolledInstrumentService.doScheduledTask(PolledInstrumentService.java:45) at org.mbari.siam.core.ScheduleTaskImpl.execute(ScheduleTaskImpl.java:58) at org.mbari.siam.core.ScheduleTimerTask.run(ScheduleTimerTask.java:54) at org.mbari.siam.core.TimerThread.mainLoop(SiamTimer.java:628) at org.mbari.siam.core.TimerThread.run(SiamTimer.java:573) 867892 WARN PolledInstrumentService [Thread-39]: acquire(): thread interrupted? 867892 INFO BaseInstrumentService [Thread-39]: Didn't remove sampling thread (total of 0 threads) 867892 ERROR PolledInstrumentService [Thread-39]: doScheduledTask: caught NoDataException: org.mbari.siam.distributed.NoDataException: acquire interrupted org.mbari.siam.distributed.NoDataException: acquire interrupted at org.mbari.siam.core.PolledInstrumentService.acquire(PolledInstrumentService.java:178) at org.mbari.siam.core.BaseInstrumentService.acquireSample(BaseInstrumentService.java:806) at org.mbari.siam.core.PolledInstrumentService.doScheduledTask(PolledInstrumentService.java:45) at org.mbari.siam.core.ScheduleTaskImpl.execute(ScheduleTaskImpl.java:58) at org.mbari.siam.core.ScheduleTimerTask.run(ScheduleTimerTask.java:54) at org.mbari.siam.core.TimerThread.mainLoop(SiamTimer.java:628) at org.mbari.siam.core.TimerThread.run(SiamTimer.java:573) 867916 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 867917 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS4 867924 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 867924 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 867924 INFO NodeManager [Thread-2]: Done with serviceRemoved() 867925 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 867925 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 867925 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 867925 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS6' 867926 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 867926 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 868725 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 868725 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS6 868740 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 868740 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 868740 INFO NodeManager [Thread-2]: Done with serviceRemoved() 868741 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 868741 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 868741 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 868741 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS11' 868742 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 868742 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 871294 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 871295 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 871295 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 871300 DEBUG PH_Responder [pH loop]: getting direction 871300 DEBUG PH_Responder [pH loop]: getting direction is [30.2406] 871300 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 873029 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.8222] from OSDT data stream (via range validator) 873751 INFO BaseInstrumentService [Thread-51]: Didn't remove sampling thread (total of 0 threads) 875482 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 875482 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS11 875490 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 875490 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 875490 INFO NodeManager [Thread-2]: Done with serviceRemoved() 875490 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 875490 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 875492 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 875492 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS12' 875492 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 875492 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 875594 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 875594 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS12 875619 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 875620 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 875620 INFO NodeManager [Thread-2]: Done with serviceRemoved() 875620 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 875620 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 875621 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 875621 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS13' 875621 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 875622 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 876838 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 876839 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS13 876852 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 876853 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 876853 INFO NodeManager [Thread-2]: Done with serviceRemoved() 876853 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 876853 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 876854 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 876854 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS14' 876855 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 876855 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 876944 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 876945 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS14 876952 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 876952 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 876952 INFO NodeManager [Thread-2]: Done with serviceRemoved() 876952 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 876952 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 876953 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 876953 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS15' 876953 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 876957 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 877549 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 877549 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS15 877555 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 877555 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 877556 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS16' 877556 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 877556 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 877557 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 877557 INFO NodeManager [Thread-2]: Done with serviceRemoved() 877558 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 877558 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 877684 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 877685 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS16 877690 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 877690 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 877690 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS17' 877690 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 877690 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 877691 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 877691 INFO NodeManager [Thread-2]: Done with serviceRemoved() 877692 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 877692 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 877875 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 877877 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS17 877883 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 877883 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 877883 INFO NodeManager [Thread-2]: Done with serviceRemoved() 877884 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 877884 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 877884 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 877885 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS8' 877885 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 877885 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 878316 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 878316 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS8 878978 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 878978 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 878978 INFO NodeManager [Thread-2]: Done with serviceRemoved() 878978 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 878978 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 878979 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 878980 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS10' 878980 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 878980 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 879082 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 879083 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS10 879089 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 879089 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 879089 INFO NodeManager [Thread-2]: Done with serviceRemoved() 879089 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 879090 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 879093 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 879094 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS19' 879094 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 879094 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 879233 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 879234 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS19 880356 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 880356 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 880357 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on '/dev/ttyS9' 880357 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 880357 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 880358 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 880358 INFO NodeManager [Thread-2]: Done with serviceRemoved() 880358 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 880359 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 881299 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 881300 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 881300 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 881304 DEBUG PH_Responder [pH loop]: getting direction 881305 DEBUG PH_Responder [pH loop]: getting direction is [30.2406] 881305 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 883030 ERROR OSDTConnectorWorker [worker_thread]: invalid value [3.822] from OSDT data stream (via range validator) 885315 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 885315 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port /dev/ttyS9 885320 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 885320 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 885320 INFO NodeManager [Thread-2]: Done with serviceRemoved() 885321 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 885321 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 885321 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 885322 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'CPUTemp' 885322 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 885322 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 885702 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 885703 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port CPUTemp 885744 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 885745 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 885745 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'ESWph' 885745 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 885745 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 885746 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 885747 INFO NodeManager [Thread-2]: Done with serviceRemoved() 885747 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 885747 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 886172 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 886173 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port ESWph 886217 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 886217 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 886217 INFO NodeManager [Thread-2]: Done with serviceRemoved() 886217 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 886218 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 886218 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 886218 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'ESWpump' 886218 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 886218 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 886219 ERROR StopWatch [Thread-127]: wait(...) failed: java.lang.InterruptedException: sleep interrupted 891222 INFO BaseInstrumentService [Thread-127]: Didn't remove sampling thread (total of 0 threads) 891305 DEBUG PH_Responder [pH loop]: vcmode: CMODE_MANUAL pcmode:CMODE_MANUAL prmode:RMODE_LIN doPump:false doValves:false vamode:AMODE_DISABLED pamode:AMODE_DISABLED 891305 ERROR PH_Responder [pH loop]: ESW pump velocity signal is null - using last valid value [0.0] 891305 ERROR PH_Responder [pH loop]: can't read valve state (valve correction disabled): valve input is null - using calculated value 891310 DEBUG PH_Responder [pH loop]: getting direction 891310 DEBUG PH_Responder [pH loop]: getting direction is [30.2406] 891310 ERROR PH_Responder [pH loop]: fwd valve:0 aft valve:2 891319 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 891320 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port ESWpump 891325 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 891325 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 891325 INFO NodeManager [Thread-2]: Done with serviceRemoved() 891326 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 891326 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 891326 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 891326 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'CO2subsys' 891327 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 891327 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 891479 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 891479 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port CO2subsys 891496 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 891497 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 891500 INFO NodeManager [Thread-2]: Done with serviceRemoved() 891497 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 891501 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: shutting down service on 'ControlLoop' 891501 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() 891501 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: Invoke service.shutdown() 891497 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 891502 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 891579 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: service.shutdown() complete 891579 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: unexportObject() succeeded for service on port ControlLoop 891602 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - postEvent() 891602 INFO NodeManager [Thread-2]: serviceRemoved(): invoke remote callbacks 891602 INFO NodeManager [Thread-2]: Done with serviceRemoved() 891603 INFO DevicePacketAggregator [Thread-8]: serviceRemoved() 891603 INFO DevicePacketAggregator [Thread-8]: done with serviceRemoved() 891603 INFO PortManager [RMI TCP Connection(23)-127.0.0.1]: removeService() - done 891604 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: application shutdown initiated 891604 INFO NodeService [RMI TCP Connection(23)-127.0.0.1]: SIAM application shutdown in 5 seconds 896609 INFO NodeService [Thread-220]: exiting application now