RRTC.setOscAdjRegister() current OAR=0 RTC.setOscAdjRegister() setting OAR=0 RTC.setOscAdjRegister() OAR now set to 0 delaying 500 ms delaying 500 ms delaying 500 ms delaying 500 ms delaying 500 ms delaying 500 ms Synchronizing RTC and Ajile System clocks Rtc.toString(long) converting 3772 Ajile System time currently: 1970/1/1 0:0:3 (3772) Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 Rtc.getTime() HOUR_CTR_REG = 0x17 Rtc.getTime() MINUTE_CTR_REG = 0x57 Rtc.getTime() SECOND_CTR_REG = 0x46 Rtc.getTime() 2002 11 18 17 57 46 Rtc.toMillis(RtcTime) ->1037642266879 Rtc.toString(long) converting 1037642270777 Ajile System time set to: 2002/11/18 17:57:50 (1037642270777) Rtc.toString(long) converting 1037642266879 RTC time set to : 2002/11/18 17:57:46 (1037642266879) At location 0 there is NOT a dpa board At location 1 there is a dpa board HdwrReg(int[]) ctor invoked DpaBoard ctor invoked At location 2 there is a dpa board HdwrReg(int[]) ctor invoked DpaBoard ctor invoked At location 3 there is a dpa board HdwrReg(int[]) ctor invoked DpaBoard ctor invoked DpaChannel ctor invoked DpaLeftChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked LeftRelayReg ctor invoked HdwrReg(int[]) ctor invoked LeftChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaLeftChannel@484150 first line of initializeBoard() Doing DpaChannel.initializeBoard() checkOnceForDpaHardware() called...DPA is available Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaLeftChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaLeftChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com2 port config: 9600 0 8 1 Base: setSerialPort() called! DpaChannel ctor invoked DpaRightChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked RightRelayReg ctor invoked HdwrReg(int[]) ctor invoked RightChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaRightChannel@482258 first line of initializeBoard() Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaRightChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaRightChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com3 port config: 9600 0 8 1 Base: setSerialPort() called! DpaChannel ctor invoked DpaLeftChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked LeftRelayReg ctor invoked HdwrReg(int[]) ctor invoked LeftChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaLeftChannel@480400 first line of initializeBoard() Doing DpaChannel.initializeBoard() checkOnceForDpaHardware() called...DPA is available Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaLeftChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaLeftChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com4 port config: 9600 0 8 1 Base: setSerialPort() called! DpaChannel ctor invoked DpaRightChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked RightRelayReg ctor invoked HdwrReg(int[]) ctor invoked RightChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaRightChannel@47e58c first line of initializeBoard() Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaRightChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaRightChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com5 port config: 9600 0 8 1 DpaChannel ctor invoked DpaLeftChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked LeftRelayReg ctor invoked HdwrReg(int[]) ctor invoked LeftChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaLeftChannel@47cfe4 first line of initializeBoard() Doing DpaChannel.initializeBoard() checkOnceForDpaHardware() called...DPA is available Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaLeftChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaLeftChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com6 port config: 9600 0 8 1 Base: setSerialPort() called! DpaChannel ctor invoked DpaRightChannel ctor invoked HdwrReg(int[]) ctor invoked RelayReg ctor invoked RightRelayReg ctor invoked HdwrReg(int[]) ctor invoked RightChannelCtrlReg ctor invoked HdwrReg(int[]) ctor invoked Initializing DpaChannel nmc.sidekick.DpaBoard$DpaRightChannel@47b170 first line of initializeBoard() Disconnecting RS485 terminators Isolating communications ground Isolating Instrument power Setting Current Limit to 1000 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 0 about to initializeCurrentCtrl()... in InitializeCurrentCtrl() DpaRightChannel movePot(dir=0; steps=99 initializeCurrentCtrl() done. After Init Present Position = 0 DpaRightChannel movePot(dir=1; steps=8 Present 0 New 8 diff 8 initializing channelCtrlReg Setting COMM and INSTR power OFF opening port: com7 port config: 9600 0 8 1 Base: setSerialPort() called! loadAndGo() Creating outbound message queue... sidearmIpAddr=192.168.0.2; sidearmRcvPort=8188 OutboundMessageQ Ctor 192.168.0.2:8188 loadAndGo() Creating inbound message queue... sidekickRcvPort=8190 InboundMessageQ ctor 8190 InboundMessageQ ctor got my port loadAndGo() Initializing drivers Driver: nmc.sidekick.Garmin_25HVS_GPS initializing channel nmc.sidekick.DpaBoard$DpaLeftChannel@484150 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 8 After Init Present Position = 8 Present 8 New 8 diff 0 Connecting Comms Ground Connecting Instrument Power startup(): Done with DPA initialization Setting GPS com port to 4800,8,1,0 initializeInstrument(): Setting Up GPS delaying 2000 ms disableAllNMEA(): Turning all NMEA messages OFF setNMEAMessage(): setting $PGRMO,,2 entring skipUntil(terminator: $PGRMO,,2 ) SkipUntil(): found terminator: $PGRMO,,2 leaving skipUntil bytesSkipped = 591 elapsed = 102 setNMEAMessage(): setting $PGRMO,GPRMC,1 entring skipUntil(terminator: $PGRMO,GPRMC,1 ) SkipUntil(): found terminator: $PGRMO,GPRMC,1 leaving skipUntil bytesSkipped = 16 elapsed = 100 setNMEAMessage(): setting $PGRMO,GPGSV,1 entring skipUntil(terminator: $PGRMO,GPGSV,1 ) SkipUntil(): found terminator: $PGRMO,GPGSV,1 leaving skipUntil bytesSkipped = 16 elapsed = 175 setNMEAMessage(): setting $PGRMO,PGRMT,1 entring skipUntil(terminator: $PGRMO,PGRMT,1 ) SkipUntil(): found terminator: $PGRMO,PGRMT,1 leaving skipUntil bytesSkipped = 16 elapsed = 118 Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.Garmin_25HVS_GPS@48ac54 DriverMessageQ.startInboundMessaging() starting Driver: nmc.sidekick.SmartStarChunkster initializing channel nmc.sidekick.DpaBoard$DpaRightChannel@482258 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 8 After Init Present Position = 8 Present 8 New 8 diff 0 Connecting Comms Ground Connecting Instrument Power startup(): Done with DPA initialization SmartStar.initializeInstrument(): entry SmartStar.initializeInstrument(): exit Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.SmartStarChunkster@48aaa8 DriverMessageQ.startInboundMessaging() starting Driver: nmc.sidekick.CompassTurnsCounter initializing channel nmc.sidekick.DpaBoard$DpaLeftChannel@480400 In setCurrentLimit(int milliamps) 500 On Entry Present Position = 8 After Init Present Position = 8 DpaLeftChannel movePot(dir=0; steps=4 Present 8 New 4 diff -4 Connecting Comms Ground Connecting Instrument Power startup(): Done with DPA initialization KVH: Setting serial port params: KVH getAttention(): try to set to polled mode leaving readUntil bytesRead = 0 outbuf len = 256 elapsed = 501 KVH getAttention(): couldn't set to polled mode DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue KVH getAttention(): try to set to polled mode leaving readUntil bytesRead = 1 outbuf len = 256 elapsed = 43 KVH getAttention(): instrument now in polled mode KVH: done with initializeInstrumen() Base: nmc.sidekick.CompassTurnsCounter.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaLeftChannel@480400) leaving readUntil bytesRead = 21 outbuf len = 32 elapsed = 65 parseDouble: + 154 5 1 + 0 CompassTurnsCounter - start sampler thread Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.CompassTurnsCounter@48a960 DriverMessageQ.startInboundMessaging() starting Driver: nmc.sidekick.WaveSensor initializing channel nmc.sidekick.DpaBoard$DpaLeftChannel@47cfe4 In setCurrentLimit(int milliamps) 3000 On Entry Present Position = 8 After Init Present Position = 8 DpaLeftChannel movePot(dir=1; steps=17 Present 8 New 25 diff 17 Connecting Comms Ground Connecting Instrument Power startup(): Done with DPA initialization nmc.sidekick.WaveSensor Synchronize time in Triaxsys to !T02-11-18 17:58:03 DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue nmc.sidekick.WaveSensor Triaxsys synched Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.WaveSensor@48a894 DriverMessageQ.startInboundMessaging() starting Driver: nmc.sidekick.SmartStarChunkster initializing channel nmc.sidekick.DpaBoard$DpaRightChannel@47b170 In setCurrentLimit(int milliamps) 1000 On Entry Present Position = 8 After Init Present Position = 8 Present 8 New 8 diff 0 Connecting Comms Ground Connecting Instrument Power startup(): Done with DPA initialization SmartStar.initializeInstrument(): entry SmartStar.initializeInstrument(): exit Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.SmartStarChunkster@48a528 DriverMessageQ.startInboundMessaging() starting Initializing ADC... Initializing initAdcCsN Adc.initAdcCsN() (REG_E_OR 4.1) Adc.initAdcCsN() (REG_E_OER 4.1) EnvHumidity Initializing RHPowerOn EnvHumidity.initRHPowerOn(): acquiring ADC EnvHumidity Powering OFF EnvHumidity.initRHPowerOn(): releasing ADC Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.EnvHumidity@48a430 DriverMessageQ.startInboundMessaging() starting Initializing ADC... initAdcCsN already initialized Initializing PressureTemp Sensor PressureTempSensor.configure() (REG_E_OER | 6) Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.EnvPressureTemp@48a138 DriverMessageQ.startInboundMessaging() starting Initializing ADC... initAdcCsN already initialized Starting Inbound Message Queue... DriverMessageQ Ctor depth 5 drvr nmc.sidekick.EnvGroundFault@489e18 DriverMessageQ.startInboundMessaging() starting commandParser.bp()after driver init loadAndGo() Starting Outbound Message Queue OutboundMessageQ.startOutboundMessaging creating thread OutboundMessageQ.startOutboundMessaging starting thread OutboundMessageQ.startOutboundMessaging returning commandParser.bp()after IBQ start loadAndGo() Starting Inbound Message Queue InboundMessageQ.startInboundMessaging() creating thread InboundMessageQ.startInboundMessaging() starting thread InboundMessageQ.startInboundMessaging() returning commandParser.bp()after OBQ start loadAndGo() Priming communications pump... sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(6000) DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue InboundMessageQ.run() InboundMessage thread starting... InboundMessageQ.getOne() Inbound blocking on socket read... OutboundMessageQ.run() thread starting... OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.run() InboundFifo thread starting... DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking Timed Out sending %<%msgType=AliveNotice%seqNo=0%len=0%>% OutboundMessageQ.put() : %<%msgType=AliveNotice%seqNo=0%len=0%>% chan=-1 ecount=0 InboundMessageQ.blockingGet(2000) OutboundMessageQ.sendOne got %<%msgType=AliveNotice%seqNo=0%len=0%>% sendOne(): msgStr.len=12 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=AliveReply%seqNo=0%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... received %<%msgType=AliveReply%seqNo=0%len=0%>% loadAndGo() Sending Initial Metadata... InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=1%len=8%channelNumber=2%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=1%len=448%channelNumber=2%endOfData=true%subseqNo=0%timestamp=1037642325830%data= FirstFixTimeout="900000" WarmupDelay="2000" GPS_BAUD="4800" GPS_DATA="8" GPS_STOP="1" GPS_PARITY="0" name="nmc.sidekick.Garmin_25HVS_GPS" prompt=" " cfgEOL=" " sampleTerminator=" " timeout="10000" maxSampleTries="15" maxSampleBytes="512" cfgBufSize="256" instrumentStartDelay="2000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% chan=-1 ecount=0 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=2%len=8%channelNumber=3%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=2%len=358%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642330144%data=nmc.sidekick.SmartStarChunkster has no additional metadata name="nmc.sidekick.SmartStarChunkster" prompt="RDY " cfgEOL=" " sampleTerminator="RDY " timeout="3000" maxSampleTries="2" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="1000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% chan=-1 ecount=0 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue loadAndGo() Just returned from blocking get detected GET_ABSOLUTE_TIME_CMD InboundMessageQ.getOne() received: %<%msgType=GetAbsoluteTimeCmd%seqNo=1%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=1%len=448%channelNumber=2%endOfData=true%subseqNo=0%timestamp=1037642325830%data= FirstFixTimeout="900000" WarmupDelay="2000" GPS_BAUD="4800" GPS_DATA="8" GPS_STOP="1" GPS_PARITY="0" name="nmc.sidekick.Garmin_25HVS_GPS" prompt=" " cfgEOL=" " sampleTerminator=" " timeout="10000" maxSampleTries="15" maxSampleBytes="512" cfgBufSize="256" instrumentStartDelay="2000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% Cerealizing GetMetadataReply, data.length=431 sendOne(): msgStr.len=460 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=2%len=358%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642330144%data=nmc.sidekick.SmartStarChunkster has no additional metadata name="nmc.sidekick.SmartStarChunkster" prompt="RDY " cfgEOL=" " sampleTerminator="RDY " timeout="3000" maxSampleTries="2" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="1000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% Cerealizing GetMetadataReply, data.length=341 sendOne(): msgStr.len=370 OutboundMessageQ.sendOne blocking Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=3%len=8%channelNumber=4%type=ALL%>% detected GET_METADATA_CMD KVH getAttention(): try to set to polled mode leaving readUntil bytesRead = 1 outbuf len = 256 elapsed = 46 KVH getAttention(): instrument now in polled mode Getting KVH metadata... leaving readUntil bytesRead = 35 outbuf len = 50 elapsed = 501 Couldn't get KVH metadata! Copy KVH metadata: offset=15, nBytes=25 OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=3%len=341%channelNumber=4%endOfData=true%subseqNo=0%timestamp=1037642332272%data= KVH metadata: Couldn't get KVH metadata name="nmc.sidekick.CompassTurnsCounter" prompt=">" cfgEOL=" " sampleTerminator=" " timeout="1000" maxSampleTries="3" maxSampleBytes="32" cfgBufSize="512" instrumentStartDelay="500" currentLimit="500" iPowerPolicy="2" cPowerPolicy="2" %>% chan=-1 ecount=0 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue InboundMessageQ.getOne() received: %<%msgType=GetAbsoluteTimeCmd%seqNo=2%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... Rtc.getTime() HOUR_CTR_REG = 0x17 OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=3%len=341%channelNumber=4%endOfData=true%subseqNo=0%timestamp=1037642332272%data= KVH metadata: Couldn't get KVH metadata name="nmc.sidekick.CompassTurnsCounter" prompt=">" cfgEOL=" " sampleTerminator=" " timeout="1000" maxSampleTries="3" maxSampleBytes="32" cfgBufSize="512" instrumentStartDelay="500" currentLimit="500" iPowerPolicy="2" cPowerPolicy="2" %>% Cerealizing GetMetadataReply, data.length=324 sendOne(): msgStr.len=353 OutboundMessageQ.sendOne blocking DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=8%len=8%channelNumber=14%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=8%len=266%channelNumber=14%endOfData=true%subseqNo=0%timestamp=1037642333617%data=nmc.sidekick.EnvGroundFault has no additional metadata name="nmc.sidekick.EnvGroundFault" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="2000" %>% chan=-1 ecount=0 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue Rtc.getTime() MINUTE_CTR_REG = 0x58 DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=4%len=8%channelNumber=6%type=ALL%>% detected GET_METADATA_CMD DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=5%len=8%channelNumber=7%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=5%len=358%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642337959%data=nmc.sidekick.SmartStarChunkster has no additional metadata name="nmc.sidekick.SmartStarChunkster" prompt="RDY " cfgEOL=" " sampleTerminator="RDY " timeout="3000" maxSampleTries="2" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="1000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% chan=-1 ecount=1 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=6%len=8%channelNumber=12%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=6%len=261%channelNumber=12%endOfData=true%subseqNo=0%timestamp=1037642338117%data=nmc.sidekick.EnvHumidity has no additional metadata name="nmc.sidekick.EnvHumidity" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="10000" %>% chan=-1 ecount=2 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue InboundMessageQ.getOne() received: %<%msgType=GetAbsoluteTimeCmd%seqNo=3%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=4%len=336%channelNumber=6%endOfData=true%subseqNo=0%timestamp=1037642338214%data=nmc.sidekick.WaveSensor has no additional metadata name="nmc.sidekick.WaveSensor" prompt="*" cfgEOL=" " sampleTerminator="*" timeout="10000" maxSampleTries="3" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="500" currentLimit="3000" iPowerPolicy="2" cPowerPolicy="2" %>% chan=-1 ecount=1 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue Rtc.getTime() SECOND_CTR_REG = 0x50 Rtc.getTime() 2002 11 18 17 58 50 Rtc.toMillis(RtcTime) ->1037642330397 Rtc.toString(long) converting 1037642339423 CmdParser(GET_ABSOLUTE_TIME): Ajile System time currently: 2002/11/18 17:58:59 (1037642339423) Rtc.toString(long) converting 1037642330397 CmdParser(GET_ABSOLUTE_TIME): RTC time currently : 2002/11/18 17:58:50 (1037642330397) OutboundMessageQ.put() : %<%msgType=AbsoluteTimeReply%seqNo=1%len=8%absoluteTime=1037642339423%>% chan=-1 ecount=3 Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected GET_ABSOLUTE_TIME_CMD DriverMessageQ.getOne() received: %<%msgType=GetMetadataCmd%seqNo=7%len=8%channelNumber=13%type=ALL%>% detected GET_METADATA_CMD OutboundMessageQ.put() : %<%msgType=GetMetadataReply%seqNo=7%len=269%channelNumber=13%endOfData=true%subseqNo=0%timestamp=1037642340677%data=nmc.sidekick.EnvPressureTemp has no additional metadata name="nmc.sidekick.EnvPressureTemp" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="10000" %>% chan=-1 ecount=4 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=8%len=266%channelNumber=14%endOfData=true%subseqNo=0%timestamp=1037642333617%data=nmc.sidekick.EnvGroundFault has no additional metadata name="nmc.sidekick.EnvGroundFault" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="2000" %>% Cerealizing GetMetadataReply, data.length=249 sendOne(): msgStr.len=278 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=5%len=358%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642337959%data=nmc.sidekick.SmartStarChunkster has no additional metadata name="nmc.sidekick.SmartStarChunkster" prompt="RDY " cfgEOL=" " sampleTerminator="RDY " timeout="3000" maxSampleTries="2" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="1000" currentLimit="1000" iPowerPolicy="2" cPowerPolicy="2" %>% Cerealizing GetMetadataReply, data.length=341 sendOne(): msgStr.len=370 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=6%len=261%channelNumber=12%endOfData=true%subseqNo=0%timestamp=1037642338117%data=nmc.sidekick.EnvHumidity has no additional metadata name="nmc.sidekick.EnvHumidity" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="10000" %>% Cerealizing GetMetadataReply, data.length=244 sendOne(): msgStr.len=273 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=GetAbsoluteTimeCmd%seqNo=4%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=4%len=336%channelNumber=6%endOfData=true%subseqNo=0%timestamp=1037642338214%data=nmc.sidekick.WaveSensor has no additional metadata name="nmc.sidekick.WaveSensor" prompt="*" cfgEOL=" " sampleTerminator="*" timeout="10000" maxSampleTries="3" maxSampleBytes="1000" cfgBufSize="512" instrumentStartDelay="500" currentLimit="3000" iPowerPolicy="2" cPowerPolicy="2" %>% Cerealizing GetMetadataReply, data.length=319 sendOne(): msgStr.len=348 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=AbsoluteTimeReply%seqNo=1%len=8%absoluteTime=1037642339423%>% sendOne(): msgStr.len=20 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=GetMetadataReply%seqNo=7%len=269%channelNumber=13%endOfData=true%subseqNo=0%timestamp=1037642340677%data=nmc.sidekick.EnvPressureTemp has no additional metadata name="nmc.sidekick.EnvPressureTemp" cfgEOL=" " timeout="15000" maxSampleTries="3" maxSampleBytes="64" cfgBufSize="512" instrumentStartDelay="10000" %>% Cerealizing GetMetadataReply, data.length=252 sendOne(): msgStr.len=281 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=GetAbsoluteTimeCmd%seqNo=5%len=0%>% InboundMessageQ.getOne() Inbound blocking on socket read... Rtc.getTime() HOUR_CTR_REG = 0x17 Rtc.getTime() MINUTE_CTR_REG = 0x59 Rtc.getTime() SECOND_CTR_REG = 0x3 Rtc.getTime() 2002 11 18 17 59 3 Rtc.toMillis(RtcTime) ->1037642343670 Rtc.toString(long) converting 1037642347695 CmdParser(GET_ABSOLUTE_TIME): Ajile System time currently: 2002/11/18 17:59:7 (1037642347695) Rtc.toString(long) converting 1037642343670 CmdParser(GET_ABSOLUTE_TIME): RTC time currently : 2002/11/18 17:59:3 (1037642343670) OutboundMessageQ.put() : %<%msgType=AbsoluteTimeReply%seqNo=2%len=8%absoluteTime=1037642347695%>% chan=-1 ecount=0 Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected GET_ABSOLUTE_TIME_CMD OutboundMessageQ.sendOne got %<%msgType=AbsoluteTimeReply%seqNo=2%len=8%absoluteTime=1037642347695%>% sendOne(): msgStr.len=20 OutboundMessageQ.sendOne blocking Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 Rtc.getTime() HOUR_CTR_REG = 0x17 Rtc.getTime() MINUTE_CTR_REG = 0x59 Rtc.getTime() SECOND_CTR_REG = 0x3 Rtc.getTime() 2002 11 18 17 59 3 Rtc.toMillis(RtcTime) ->1037642343994 Rtc.toString(long) converting 1037642349019 CmdParser(GET_ABSOLUTE_TIME): Ajile System time currently: 2002/11/18 17:59:9 (1037642349019) Rtc.toString(long) converting 1037642343994 CmdParser(GET_ABSOLUTE_TIME): RTC time currently : 2002/11/18 17:59:3 (1037642343994) OutboundMessageQ.put() : %<%msgType=AbsoluteTimeReply%seqNo=3%len=8%absoluteTime=1037642349019%>% chan=-1 ecount=0 Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected GET_ABSOLUTE_TIME_CMD OutboundMessageQ.sendOne got %<%msgType=AbsoluteTimeReply%seqNo=3%len=8%absoluteTime=1037642349019%>% sendOne(): msgStr.len=20 OutboundMessageQ.sendOne blocking Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 Rtc.getTime() HOUR_CTR_REG = 0x17 Rtc.getTime() MINUTE_CTR_REG = 0x59 Rtc.getTime() SECOND_CTR_REG = 0x4 Rtc.getTime() 2002 11 18 17 59 4 Rtc.toMillis(RtcTime) ->1037642344268 Rtc.toString(long) converting 1037642349292 CmdParser(GET_ABSOLUTE_TIME): Ajile System time currently: 2002/11/18 17:59:9 (1037642349292) Rtc.toString(long) converting 1037642344268 CmdParser(GET_ABSOLUTE_TIME): RTC time currently : 2002/11/18 17:59:4 (1037642344268) OutboundMessageQ.put() : %<%msgType=AbsoluteTimeReply%seqNo=4%len=8%absoluteTime=1037642349292%>% chan=-1 ecount=0 Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected GET_ABSOLUTE_TIME_CMD OutboundMessageQ.sendOne got %<%msgType=AbsoluteTimeReply%seqNo=4%len=8%absoluteTime=1037642349292%>% sendOne(): msgStr.len=20 OutboundMessageQ.sendOne blocking Rtc.getTime() YEAR_CTR_REG = 0x2 Rtc.getTime(), mccb = 0x91 Rtc.getTime() HOUR_CTR_REG = 0x17 Rtc.getTime() MINUTE_CTR_REG = 0x59 Rtc.getTime() SECOND_CTR_REG = 0x6 Rtc.getTime() 2002 11 18 17 59 6 Rtc.toMillis(RtcTime) ->1037642346585 Rtc.toString(long) converting 1037642350608 CmdParser(GET_ABSOLUTE_TIME): Ajile System time currently: 2002/11/18 17:59:10 (1037642350608) Rtc.toString(long) converting 1037642346585 CmdParser(GET_ABSOLUTE_TIME): RTC time currently : 2002/11/18 17:59:6 (1037642346585) OutboundMessageQ.put() : %<%msgType=AbsoluteTimeReply%seqNo=5%len=8%absoluteTime=1037642350608%>% chan=-1 ecount=0 Around the loop 0 time(s). InboundMessageQ.blockingGet() entry OutboundMessageQ.sendOne got %<%msgType=AbsoluteTimeReply%seqNo=5%len=8%absoluteTime=1037642350608%>% sendOne(): msgStr.len=20 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=6%len=4%channelNumber=4%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=7%len=4%channelNumber=3%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=8%len=4%channelNumber=7%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=9%len=4%channelNumber=2%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=10%len=4%channelNumber=6%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=11%len=4%channelNumber=13%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=12%len=4%channelNumber=12%>% InboundMessageQ.getOne() Inbound blocking on socket read... InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=13%len=4%channelNumber=14%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=13%len=4%channelNumber=14%>% detected POLL_CMD Base: calling requestSample() Base: Calling readSample() readSample() Measuring HI side EnvGroundFault.readAverageCounts(): acquiring ADC getSample() Reading adc channel 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 0 counts = 605 sum = 605 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 1 counts = 94 sum = 699 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 2 counts = 16 sum = 715 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 3 counts = 4 sum = 719 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 4 counts = 1 sum = 720 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 5 counts = 0 sum = 720 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 6 counts = 1 sum = 721 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 7 counts = 1 sum = 722 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 8 counts = 1 sum = 723 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 9 counts = 0 sum = 723 Average of 10 readings is 72 EnvGroundFault.readAverageCounts(): releasing ADC readSample() Measuring LO side EnvGroundFault.readAverageCounts(): acquiring ADC getSample() Reading adc channel 1 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 0 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 1 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 2 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 3 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 4 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 5 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 6 counts = 0 sum = 0 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 7 counts = 1 sum = 1 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=6%len=4%channelNumber=4%>% detected POLL_CMD nmc.sidekick.CompassTurnsCounter: pre-acquireAndProcess(): 125388 Base: nmc.sidekick.CompassTurnsCounter.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaLeftChannel@480400) leaving readUntil bytesRead = 21 outbuf len = 32 elapsed = 3 parseDouble: + 159 7 1 + 0 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=6%len=78%channelNumber=4%endOfData=true%subseqNo=0%timestamp=1037642359943%data=hdg=159,turns=ERR,initHdg=154,initTime=1037642281,hdgOffset=0%>% chan=4 ecount=0 nmc.sidekick.CompassTurnsCounter: post-acquireAndProcess(): 116108 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue adc reading 8 counts = 0 sum = 1 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) adc reading 9 counts = 0 sum = 1 Average of 10 readings is 0 EnvGroundFault.readAverageCounts(): releasing ADC nmc.sidekick.EnvGroundFault readSample() Returning...0,72,8057,0,0,5801 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=13%len=35%channelNumber=14%endOfData=true%subseqNo=0%timestamp=1037642360084%data=0,72,8057,0,0,5801%>% chan=14 ecount=1 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=12%len=4%channelNumber=12%>% detected POLL_CMD Base: calling requestSample() EnvHumidity Powering ON sensorPowerOn() Warming up sensor.... delaying 10000 ms OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=6%len=78%channelNumber=4%endOfData=true%subseqNo=0%timestamp=1037642359943%data=hdg=159,turns=ERR,initHdg=154,initTime=1037642281,hdgOffset=0%>% Cerealizing PolledDataReply, data.length=61 PDR.cerealize(): dlen=61 ch=4 fmem=105612 sendOne(): msgStr.len=90 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=13%len=35%channelNumber=14%endOfData=true%subseqNo=0%timestamp=1037642360084%data=0,72,8057,0,0,5801%>% Cerealizing PolledDataReply, data.length=18 PDR.cerealize(): dlen=18 ch=14 fmem=100244 sendOne(): msgStr.len=47 OutboundMessageQ.sendOne blocking DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=7%len=4%channelNumber=3%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 97868 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@482258) leaving readUntil bytesRead = 152 outbuf len = 1000 elapsed = 123 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen148 ch=3 fmem=93776 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=7%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642361640%data=800 1A1 000137 001 1A3 0 01 034 000137 000 037 0 02 038 000137 001 03C 0 03 33F 000137 001 341 0 04 000 000137 000 000 0 05 000 000137 000 000 0 %>% chan=3 ecount=0 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 88144 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=11%len=4%channelNumber=13%>% detected POLL_CMD Base: calling requestSample() Base: Calling readSample() DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=10%len=4%channelNumber=6%>% detected POLL_CMD nmc.sidekick.WaveSensor: pre-acquireAndProcess(): 87588 Base: calling chan6.requestSample() nmc.sidekick.WaveSensor: startSample() using !R1 leaving readUntil bytesRead = 4 outbuf len = 256 elapsed = 1 nmc.sidekick.WaveSensorstartSample(): Prompt detected: 1 leaving readUntil bytesRead = 0 outbuf len = 256 elapsed = 1001 nmc.sidekick.WaveSensor: timeout in startSample(), retry... DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=9%len=4%channelNumber=2%>% detected POLL_CMD nmc.sidekick.Garmin_25HVS_GPS: pre-acquireAndProcess(): 83028 Base: calling chan2.requestSample() getNextMessage(): Synchronizing... entring skipUntil(terminator: ) SkipUntil(): found terminator: leaving skipUntil bytesSkipped = 72 elapsed = 121 requestSample(): done Base: Calling chan2.readSample() readSample(): acquiring fix... getNextMessage(): Synchronizing... entring skipUntil(terminator: ) SkipUntil(): found terminator: leaving skipUntil bytesSkipped = 52 elapsed = 98 entring skipUntil(terminator: $GPRMC ) SkipUntil(): found terminator: $GPRMC leaving skipUntil bytesSkipped = 140 elapsed = 680 leaving readUntil bytesRead = 66 outbuf len = 512 elapsed = 109 getMessage(): looking for $GPRMC got $GPRMC,180213,A,3648.1552,N,12147.2609,W,000.0,000.0,181102,015.1,E*65 (64,70) bytes Got valid GPRMC message Base: Calling chan2.processSample() Base: nmc.sidekick.Garmin_25HVS_GPS.processSample(): entry (nmc.sidekick.DpaBoard$DpaLeftChannel@484150) Base: nmc.sidekick.Garmin_25HVS_GPS.processSample(): exit (nmc.sidekick.DpaBoard$DpaLeftChannel@484150) Base: Calling chan2.outbound.put() datalen=70 sbuflen=512 nBytes=70 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=9%len=87%channelNumber=2%endOfData=true%subseqNo=0%timestamp=1037642365461%data=$GPRMC,180213,A,3648.1552,N,12147.2609,W,000.0,000.0,181102,015.1,E*65%>% chan=2 ecount=1 nmc.sidekick.Garmin_25HVS_GPS.acquireAndProcess(): dlen70 ch=2 fmem=56136 nmc.sidekick.Garmin_25HVS_GPS: post-acquireAndProcess(): 55316 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=8%len=4%channelNumber=7%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 54796 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@47b170) leaving readUntil bytesRead = 940 outbuf len = 1000 elapsed = 953 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=50704 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=00 C28 00014E C19 C68 0 01 040 00014E 03F 042 0 02 042 00014E 041 044 0 03 03F 00014E 03E 041 0 04 AF3 00014E AF1 AFE 0 05 D46 00014E D42 D4D 0 %>% chan=7 ecount=2 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=44756 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=06 D44 00014E D3F D4C 0 07 D53 00014E D4D D5C 0 08 D3F 00014E D3A D47 0 09 1C7 000006 1C4 1CD 0 0A 3EF 000006 3ED 3F1 0 0B 776 00014E 75C 797 0 %>% chan=7 ecount=3 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=38808 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=0C 7A1 00014E 7A0 7A6 0 0D 799 00014E 798 79E 0 0E 792 00014E 791 797 0 0F 78B 00014E 78A 78F 0 10 7A1 00014E 79F 7A5 0 11 7BB 00014E 7BA 7BF 0 %>% chan=7 ecount=4 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=32860 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=12 7A9 00014E 7A8 7AD 0 13 7C2 00014E 7AF 7C7 0 14 7A5 00014E 7A4 7A9 0 15 7AA 00014E 7A9 7AE 0 16 798 00014E 797 79D 0 17 843 000D0F 7C2 8E4 0 %>% chan=7 ecount=4 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=23272 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=18 7B7 000D0F 7B1 7BE 0 19 03F 000006 03E 040 0 1A 041 000006 041 041 0 1B 041 000006 041 042 0 1C 040 000006 040 041 0 1D 041 000006 041 041 0 %>% chan=7 ecount=5 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=17324 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=1E 03F 000006 03F 03F 0 1F 041 000006 041 042 0 20 043 000006 043 044 0 21 4B6 000006 4B6 4B7 0 22 1B2 000006 1AF 1B8 0 23 086 000006 086 087 0 %>% chan=7 ecount=6 nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen936 ch=7 fmem=11376 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% chan=7 ecount=7 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 5744 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=7%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642361640%data=800 1A1 000137 001 1A3 0 01 034 000137 000 037 0 02 038 000137 001 03C 0 03 33F 000137 001 341 0 04 000 000137 000 000 0 05 000 000137 000 000 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=3 fmem=4312 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking C1 is 1776 C2 is 5277 C3 is 308 C4 is 236 C5 is 2318 C6 is 49 Base: Calling readSample() EnvHumidity.readSample(): acquiring ADC... EnvHumidity.readSample() Reading ADC... Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 0 counts = 2 sum = 2 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 1 counts = 505 sum = 507 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 2 counts = 655 sum = 1162 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 3 counts = 716 sum = 1878 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 4 counts = 716 sum = 2594 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 5 counts = 715 sum = 3309 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 6 counts = 715 sum = 4024 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 7 counts = 716 sum = 4740 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 8 counts = 715 sum = 5455 Adc.getReading() (REG_E_OR 4.0) Adc.getReading() (REG_E_OR 4.1) EnvHumidity.readSample(): ADC reading 9 counts = 715 sum = 6170 EnvHumidity.readSample(): releasing ADC... EnvHumidity.readSample(): Average of 10 readings is 617 EnvHumidity.readSample(): Turning sensor power off EnvHumidity Powering OFF nmc.sidekick.EnvHumidityEnvHumidity.readSample(): Returning...617 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=12%len=20%channelNumber=12%endOfData=true%subseqNo=0%timestamp=1037642374128%data=617%>% chan=12 ecount=7 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue leaving readUntil bytesRead = 0 outbuf len = 256 elapsed = 1001 nmc.sidekick.WaveSensor: timeout in startSample(), retry... OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=9%len=87%channelNumber=2%endOfData=true%subseqNo=0%timestamp=1037642365461%data=$GPRMC,180213,A,3648.1552,N,12147.2609,W,000.0,000.0,181102,015.1,E*65%>% Cerealizing PolledDataReply, data.length=70 PDR.cerealize(): dlen=70 ch=2 fmem=106284 sendOne(): msgStr.len=99 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=99524 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=92604 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=85684 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=78764 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=71844 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=64924 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=8%len=167%channelNumber=7%endOfData=true%subseqNo=0%timestamp=1037642366665%data=24 083 000006 083 084 0 25 081 000006 081 082 0 26 340 000006 340 341 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=7 fmem=58004 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=12%len=20%channelNumber=12%endOfData=true%subseqNo=0%timestamp=1037642374128%data=617%>% Cerealizing PolledDataReply, data.length=3 PDR.cerealize(): dlen=3 ch=12 fmem=52548 sendOne(): msgStr.len=32 OutboundMessageQ.sendOne blocking Pressure = 1027 Temp = 29 nmc.sidekick.EnvPressureTempreadSample() Returning...29,1027 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=11%len=24%channelNumber=13%endOfData=true%subseqNo=0%timestamp=1037642379309%data=29,1027%>% chan=13 ecount=0 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=11%len=24%channelNumber=13%endOfData=true%subseqNo=0%timestamp=1037642379309%data=29,1027%>% Cerealizing PolledDataReply, data.length=7 PDR.cerealize(): dlen=7 ch=13 fmem=42832 sendOne(): msgStr.len=36 OutboundMessageQ.sendOne blocking leaving readUntil bytesRead = 0 outbuf len = 256 elapsed = 1001 nmc.sidekick.WaveSensor: timeout in startSample(), retry... java.io.IOException: WaveSensor.startSample() failed DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=14%len=4%channelNumber=3%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=14%len=4%channelNumber=3%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 34540 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@482258) leaving readUntil bytesRead = 148 outbuf len = 1000 elapsed = 130 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen144 ch=3 fmem=30448 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=14%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642471804%data=00 1A0 0001B5 001 1A2 0 01 034 0001B5 000 037 0 02 038 0001B5 001 03B 0 03 33F 0001B5 001 341 0 04 000 0001B5 000 000 0 05 000 0001B5 000 000 0 %>% chan=3 ecount=0 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 24808 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=14%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642471804%data=00 1A0 0001B5 001 1A2 0 01 034 0001B5 000 037 0 02 038 0001B5 001 03B 0 03 33F 0001B5 001 341 0 04 000 0001B5 000 000 0 05 000 0001B5 000 000 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=3 fmem=19988 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=15%len=4%channelNumber=3%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=15%len=4%channelNumber=3%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 13248 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@482258) leaving readUntil bytesRead = 148 outbuf len = 1000 elapsed = 123 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen144 ch=3 fmem=9156 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=15%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642591805%data=00 1A1 0001DB 001 1A4 0 01 034 0001DB 000 037 0 02 038 0001DB 001 03C 0 03 33F 0001DB 001 341 0 04 000 0001DB 000 000 0 05 000 0001DB 000 000 0 %>% chan=3 ecount=0 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 3516 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=15%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642591805%data=00 1A1 0001DB 001 1A4 0 01 034 0001DB 000 037 0 02 038 0001DB 001 03C 0 03 33F 0001DB 001 341 0 04 000 0001DB 000 000 0 05 000 0001DB 000 000 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=3 fmem=122480 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=16%len=4%channelNumber=3%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=16%len=4%channelNumber=3%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 115776 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@482258) leaving readUntil bytesRead = 148 outbuf len = 1000 elapsed = 124 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen144 ch=3 fmem=111684 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=16%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642711807%data=00 1A1 0001DC 001 1A3 0 01 034 0001DC 000 036 0 02 038 0001DC 001 03C 0 03 33F 0001DC 001 341 0 04 000 0001DC 000 000 0 05 000 0001DC 000 000 0 %>% chan=3 ecount=0 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 106044 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=16%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642711807%data=00 1A1 0001DC 001 1A3 0 01 034 0001DC 000 036 0 02 038 0001DC 001 03C 0 03 33F 0001DC 001 341 0 04 000 0001DC 000 000 0 05 000 0001DC 000 000 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=3 fmem=101224 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking InboundMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=17%len=4%channelNumber=3%>% InboundMessageQ.getOne() Inbound blocking on socket read... loadAndGo() Just returned from blocking get detected POLL_CMD Around the loop 0 time(s). InboundMessageQ.blockingGet() entry DriverMessageQ.getOne() received: %<%msgType=PollCmd%seqNo=17%len=4%channelNumber=3%>% detected POLL_CMD nmc.sidekick.SmartStarChunkster: pre-acquireAndProcess(): 94484 nmc.sidekick.SmartStarChunkster.requestSample() SmartStar.requestSample(): entry SmartStar.requestSample(): exit nmc.sidekick.SmartStarChunkster.readSample() Base: nmc.sidekick.SmartStarChunkster.readSample(): entry/exit (nmc.sidekick.DpaBoard$DpaRightChannel@482258) leaving readUntil bytesRead = 148 outbuf len = 1000 elapsed = 129 nmc.sidekick.SmartStarChunkster.processSample() nmc.sidekick.SmartStarChunksteracquireAndProcess(): dlen144 ch=3 fmem=90392 OutboundMessageQ.put() : %<%msgType=PolledDataReply%seqNo=17%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642831800%data=00 1A1 0001DB 001 1A4 0 01 034 0001DB 000 037 0 02 038 0001DB 001 03C 0 03 33F 0001DB 001 341 0 04 000 0001DB 000 000 0 05 000 0001DB 000 000 0 %>% chan=3 ecount=0 nmc.sidekick.SmartStarChunkster: post-acquireAndProcess(): 84752 DriverMessageQ.getOne() InboundFifo blocking on socket read... DriverMessageQ.blockingGet() doing blocking dequeue OutboundMessageQ.sendOne got %<%msgType=PolledDataReply%seqNo=17%len=167%channelNumber=3%endOfData=true%subseqNo=0%timestamp=1037642831800%data=00 1A1 0001DB 001 1A4 0 01 034 0001DB 000 037 0 02 038 0001DB 001 03C 0 03 33F 0001DB 001 341 0 04 000 0001DB 000 000 0 05 000 0001DB 000 000 0 %>% Cerealizing PolledDataReply, data.length=150 PDR.cerealize(): dlen=150 ch=3 fmem=79932 sendOne(): msgStr.len=179 OutboundMessageQ.sendOne blocking