EPICS Controls Argonne National Laboratory

Experimental Physics and
Industrial Control System

1994  1995  1996  1997  1998  1999  2000  2001  2002  2003  2004  2005  2006  2007  <20082009  2010  2011  2012  2013  2014  2015  2016  2017  2018  2019  2020  2021  2022  2023  2024  Index 1994  1995  1996  1997  1998  1999  2000  2001  2002  2003  2004  2005  2006  2007  <20082009  2010  2011  2012  2013  2014  2015  2016  2017  2018  2019  2020  2021  2022  2023  2024 
<== Date ==> <== Thread ==>

Subject: Re: RE: RE: problem of using Streamdevice
From: "Silver" <[email protected]>
To: "tech-talk" <[email protected]>
Date: Mon, 6 Oct 2008 11:21:59 +0800
hi, 

  Here is the log file coming with StreamDevice, can anyone give me some suggestions? Thanks a lot.

2008/10/06 11:14:01.888 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:01.888 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:01.889 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:01.889 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:01.889 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:01.889 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:01.889 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:01.889 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:01.889 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:01.889 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:01.889 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:01.890 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:01.890 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:01.890 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:01.890 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:01.890 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:01.890 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:01.890 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:01.890 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:01.892 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:01.892 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:01.892 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:01.892 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:01.892 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:01.893 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:02.892 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:02.892 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>z;<05>"
2008/10/06 11:14:02.892 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>z;<05>", size=7)
2008/10/06 11:14:02.892 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>z;<05>", size 8
2008/10/06 11:14:02.892 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:02.892 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>z;<05>"
2008/10/06 11:14:02.892 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:02.892 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>z;<05>"
2008/10/06 11:14:02.892 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00381470
2008/10/06 11:14:02.892 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:02.892 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:02.892 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:02.892 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:02.892 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:02.892 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:02.893 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:02.893 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:02.893 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:02.896 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:02.896 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:02.896 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:02.896 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:02.896 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:02.896 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:02.896 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:02.896 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:02.896 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:02.896 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:02.896 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:02.897 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:02.897 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:02.899 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:02.899 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:02.899 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:02.899 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:02.899 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:02.899 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:03.896 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:03.896 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>z;<05>"
2008/10/06 11:14:03.896 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>z;<05>", size=7)
2008/10/06 11:14:03.896 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>z;<05>", size 8
2008/10/06 11:14:03.896 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:03.896 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>z;<05>"
2008/10/06 11:14:03.896 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:03.896 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>z;<05>"
2008/10/06 11:14:03.896 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00381470
2008/10/06 11:14:03.896 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:03.896 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:03.896 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:03.896 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:03.896 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:03.897 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:03.897 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:03.897 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:03.897 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:03.904 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:03.904 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:03.904 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:03.904 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:03.904 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:03.904 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:03.904 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:03.904 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:03.904 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:03.904 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:03.905 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:03.905 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:03.905 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:03.907 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:03.907 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:03.907 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:03.907 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:03.907 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:03.907 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:04.904 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:04.904 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>p;<0f>"
2008/10/06 11:14:04.904 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>p;<0f>", size=7)
2008/10/06 11:14:04.904 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>p;<0f>", size 8
2008/10/06 11:14:04.904 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:04.904 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>p;<0f>"
2008/10/06 11:14:04.904 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:04.904 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>p;<0f>"
2008/10/06 11:14:04.904 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00366211
2008/10/06 11:14:04.904 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:04.904 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:04.904 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:04.904 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:04.904 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:04.904 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:04.905 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:04.905 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:04.905 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:04.912 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:04.912 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:04.912 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:04.912 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:04.912 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:04.912 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:04.912 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:04.912 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:04.912 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:04.912 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:04.913 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:04.913 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:04.913 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:04.915 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:04.915 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:04.915 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:04.915 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:04.915 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:04.915 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:05.913 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:05.913 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:05.914 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:05.914 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:05.914 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:05.914 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:05.914 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:05.914 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:05.914 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:05.914 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:05.914 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:05.914 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:05.914 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:05.914 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:05.914 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:05.914 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:05.915 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:05.915 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:05.920 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:05.920 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:05.920 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:05.920 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:05.920 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:05.920 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:05.920 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:05.920 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:05.920 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:05.921 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:05.921 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:05.921 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:05.921 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:05.921 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:05.923 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:05.923 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:05.923 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:05.923 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:05.923 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:05.923 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:06.920 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:06.920 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:06.920 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:06.920 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:06.920 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:06.920 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:06.920 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:06.920 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:06.920 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:06.920 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:06.920 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:06.920 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:06.920 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:06.920 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:06.921 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:06.921 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:06.921 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:06.921 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:06.928 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:06.928 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:06.928 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:06.928 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:06.928 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:06.928 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:06.928 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:06.928 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:06.928 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:06.928 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:06.928 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:06.928 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:06.929 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:06.929 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:06.929 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:06.929 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:06.929 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:06.929 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:06.929 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:06.931 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:06.931 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:06.931 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:06.931 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:06.931 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:06.931 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:07.929 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:07.929 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:07.929 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:07.929 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:07.929 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:07.929 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:07.929 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:07.929 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:07.929 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:07.929 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:07.929 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:07.929 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:07.929 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:07.929 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:07.929 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:07.929 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:07.929 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:07.929 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:07.936 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:07.936 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:07.936 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:07.936 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:07.936 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:07.936 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:07.936 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:07.936 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:07.936 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:07.937 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:07.937 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:07.937 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:07.937 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:07.939 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:07.939 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:07.939 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:07.939 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:07.939 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:07.939 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:08.936 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:08.936 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:08.936 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:08.936 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:08.936 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:08.936 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:08.936 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:08.936 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:08.936 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:08.937 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:08.937 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:08.937 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:08.937 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:08.937 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:08.937 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:08.937 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:08.937 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:08.937 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:08.944 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:08.944 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:08.944 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:08.944 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:08.944 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:08.944 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:08.944 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:08.944 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:08.944 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:08.945 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:08.945 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:08.945 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:08.945 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:08.945 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:08.947 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:08.947 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:08.947 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:08.947 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:08.947 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:08.947 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:09.944 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:09.944 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>z;<05>"
2008/10/06 11:14:09.944 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>z;<05>", size=7)
2008/10/06 11:14:09.944 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>z;<05>", size 8
2008/10/06 11:14:09.944 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:09.944 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>z;<05>"
2008/10/06 11:14:09.945 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:09.945 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>z;<05>"
2008/10/06 11:14:09.945 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00381470
2008/10/06 11:14:09.945 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:09.945 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:09.945 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:09.945 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:09.945 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:09.945 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:09.945 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:09.945 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:09.945 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:09.952 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:09.952 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:09.952 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:09.952 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:09.952 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:09.952 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:09.952 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:09.952 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:09.952 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:09.952 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:09.952 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:09.952 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:09.952 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:09.953 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:09.953 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:09.953 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:09.953 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:09.953 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:09.953 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:09.955 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:09.955 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:09.955 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:09.955 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:09.955 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:09.955 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:10.952 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:10.953 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>u;<0a>"
2008/10/06 11:14:10.953 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>u;<0a>", size=7)
2008/10/06 11:14:10.953 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>u;<0a>", size 8
2008/10/06 11:14:10.953 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:10.953 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>u;<0a>"
2008/10/06 11:14:10.953 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:10.953 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>u;<0a>"
2008/10/06 11:14:10.953 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00373840
2008/10/06 11:14:10.953 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:10.953 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:10.953 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:10.953 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:10.953 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:10.953 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:10.953 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:10.953 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:10.954 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:10.960 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:10.960 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:10.960 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:10.960 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:10.960 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:10.960 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:10.960 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:10.960 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:10.960 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:10.960 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:10.960 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:10.961 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:10.961 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:10.961 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:10.961 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:10.961 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:10.961 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:10.961 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:10.961 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:10.963 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:10.963 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:10.963 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:10.963 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:10.963 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:10.963 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:11.964 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:11.964 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>p;<0f>"
2008/10/06 11:14:11.964 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>p;<0f>", size=7)
2008/10/06 11:14:11.964 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>p;<0f>", size 8
2008/10/06 11:14:11.964 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:11.964 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>p;<0f>"
2008/10/06 11:14:11.964 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:11.964 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>p;<0f>"
2008/10/06 11:14:11.964 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00366211
2008/10/06 11:14:11.965 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:11.965 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:11.965 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:11.965 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:11.965 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:11.965 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:11.965 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:11.965 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:11.965 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:11.968 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:11.968 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:11.968 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:11.968 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:11.968 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:11.968 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:11.968 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:11.968 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:11.968 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:11.969 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:11.969 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:11.969 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:11.969 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:11.971 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:11.971 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:11.971 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:11.971 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:11.971 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:11.971 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:12.968 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:12.968 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00><82>;?
2008/10/06 11:14:12.968 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00><82>;?, size=7)
2008/10/06 11:14:12.968 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00><82>;?, size 8
2008/10/06 11:14:12.968 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:12.968 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00><82>;?
2008/10/06 11:14:12.968 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:12.968 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00><82>;?
2008/10/06 11:14:12.969 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00396729
2008/10/06 11:14:12.969 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:12.969 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:12.969 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:12.969 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:12.969 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:12.969 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:12.969 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:12.969 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:12.969 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:12.976 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:12.976 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:12.976 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:12.976 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:12.976 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:12.976 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:12.976 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:12.976 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:12.976 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:12.976 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:12.976 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:12.977 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:12.977 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:12.977 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:12.977 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:12.977 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:12.977 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:12.977 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:12.977 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:12.979 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:12.979 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:12.979 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:12.979 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:12.979 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:12.979 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:13.976 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:13.976 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:13.976 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:13.976 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:13.976 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:13.976 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:13.976 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:13.977 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:13.977 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:13.977 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:13.977 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:13.977 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:13.977 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:13.977 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:13.977 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:13.977 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:13.977 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:13.977 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:13.984 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:13.984 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:13.984 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:13.984 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:13.984 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:13.984 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:13.984 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:13.984 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:13.984 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:13.984 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:13.984 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:13.985 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:13.985 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:13.985 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:13.985 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:13.985 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:13.985 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:13.985 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:13.985 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:13.987 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:13.987 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:13.987 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:13.987 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:13.987 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:13.987 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:14.984 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:14.984 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>p;<0f>"
2008/10/06 11:14:14.984 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>p;<0f>", size=7)
2008/10/06 11:14:14.984 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>p;<0f>", size 8
2008/10/06 11:14:14.984 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:14.984 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>p;<0f>"
2008/10/06 11:14:14.984 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:14.985 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>p;<0f>"
2008/10/06 11:14:14.985 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00366211
2008/10/06 11:14:14.985 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:14.985 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:14.985 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:14.985 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:14.985 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:14.985 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:14.985 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:14.985 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:14.985 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:14.992 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:14.992 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:14.992 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:14.992 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:14.992 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:14.992 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:14.992 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:14.992 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:14.992 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:14.992 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:14.993 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:14.993 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:14.993 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:14.993 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:14.995 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:14.995 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:14.995 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:14.995 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:14.995 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:14.995 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:15.996 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:15.997 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>k;<14>"
2008/10/06 11:14:15.997 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>k;<14>", size=7)
2008/10/06 11:14:15.997 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>k;<14>", size 8
2008/10/06 11:14:15.997 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:15.997 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>k;<14>"
2008/10/06 11:14:15.997 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:15.997 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>k;<14>"
2008/10/06 11:14:15.997 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00358582
2008/10/06 11:14:15.997 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:15.997 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:15.997 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:15.997 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:15.997 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:15.998 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:15.998 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:15.998 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:15.998 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:16.000 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:16.000 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:16.000 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:16.000 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:16.000 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:16.000 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:16.000 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:16.000 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:16.001 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:16.001 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:16.001 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:16.001 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:16.003 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:16.003 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:16.003 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:16.003 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:16.003 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:16.003 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:17.000 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:17.000 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00>z;<05>"
2008/10/06 11:14:17.000 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00>z;<05>", size=7)
2008/10/06 11:14:17.000 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00>z;<05>", size 8
2008/10/06 11:14:17.001 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:17.001 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00>z;<05>"
2008/10/06 11:14:17.001 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:17.001 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00>z;<05>"
2008/10/06 11:14:17.001 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00381470
2008/10/06 11:14:17.001 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""
2008/10/06 11:14:17.001 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = end
2008/10/06 11:14:17.001 terminal StreamCore.cc:519: StreamCore::finishProtocol(DZ:ai, status=Success) bus owner
2008/10/06 11:14:17.001 terminal AsynDriverInterface.cc:545: AsynDriverInterface::unlock(DZ:ai)
2008/10/06 11:14:17.001 terminal AsynDriverInterface.cc:1267: AsynDriverInterface::finish(DZ:ai) start
2008/10/06 11:14:17.001 terminal AsynDriverInterface.cc:1277: AsynDriverInterface::finish(DZ:ai) done
2008/10/06 11:14:17.001 cbLow StreamEpics.cc:891: streamRecordProcessCallback(DZ:ai) processing record
2008/10/06 11:14:17.001 cbLow StreamEpics.cc:696: Stream::process(DZ:ai) ready. convert
2008/10/06 11:14:17.001 cbLow StreamEpics.cc:896: streamRecordProcessCallback(DZ:ai) processing record done
2008/10/06 11:14:17.008 scan0.5 StreamEpics.cc:708: Stream::process(DZ:ai) start
2008/10/06 11:14:17.008 scan0.5 StreamCore.cc:413: StreamCore::startProtocol(DZ:ai, startMode=StartNormal)
2008/10/06 11:14:17.008 scan0.5 StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = out
2008/10/06 11:14:17.008 scan0.5 StreamCore.cc:602: StreamCore::evalOut: outputLine = "?81><00><81>"
2008/10/06 11:14:17.008 scan0.5 StreamCore.cc:613: StreamCore::evalOut(DZ:ai): lockRequest(5000)
2008/10/06 11:14:17.008 scan0.5 AsynDriverInterface.cc:464: AsynDriverInterface::lockRequest(DZ:ai, 5000 msec)
2008/10/06 11:14:17.008 scan0.5 StreamEpics.cc:719: Stream::process(DZ:ai): protocol started
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Lock
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:534: AsynDriverInterface::lockHandler(DZ:ai)
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:496: AsynDriverInterface::connectToAsynPort(DZ:ai)
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:505: AsynDriverInterface::connectToAsynPort(DZ:ai) is already connected
2008/10/06 11:14:17.009 terminal StreamCore.cc:795: StreamCore::lockCallback(DZ:ai, status=Success)
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:557: AsynDriverInterface::writeRequest(DZ:ai, "?81><00><81>", 100 msec)
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Write
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:585: AsynDriverInterface::writeHandler(DZ:ai)
2008/10/06 11:14:17.009 terminal StreamCore.cc:821: StreamCore::writeCallback(DZ:ai, status=Success)
2008/10/06 11:14:17.009 terminal StreamCore.cc:554: StreamCore::evalCommand(DZ:ai): activeCommand = in
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:674: AsynDriverInterface::readRequest(DZ:ai, 10000 msec reply, 1000 msec read, expect 0 bytes, asyn=no)
2008/10/06 11:14:17.009 terminal AsynDriverInterface.cc:1287: AsynDriverInterface::handleRequest(DZ:ai) Read
2008/10/06 11:14:17.011 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=1, ...) [timeout=10.000000 seconds] = asynSuccess
2008/10/06 11:14:17.011 terminal AsynDriverInterface.cc:830: AsynDriverInterface::readHandler(DZ:ai): received 1 of 1 bytes "U" eomReason=CNT
2008/10/06 11:14:17.011 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoSuccess input="U", size=1)
2008/10/06 11:14:17.011 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U", size 1
2008/10/06 11:14:17.011 terminal StreamCore.cc:1028: StreamCore::readCallback(DZ:ai) wait for more input
2008/10/06 11:14:17.011 terminal AsynDriverInterface.cc:935: AsynDriverInterface::readHandler(DZ:ai) readMore=-1 bytesToRead=63
2008/10/06 11:14:18.012 terminal AsynDriverInterface.cc:803: AsynDriverInterface::readHandler(DZ:ai): read(..., bytesToRead=63, ...) [timeout=1.000000 seconds] = asynTimeout
2008/10/06 11:14:18.012 terminal AsynDriverInterface.cc:889: AsynDriverInterface::readHandler(DZ:ai): ioAction=Read, timeout after 7 of 63 bytes "@<04><00><00><7f>;<00>"
2008/10/06 11:14:18.012 terminal StreamCore.cc:910: StreamCore::readCallback(DZ:ai, status=StreamIoTimeout input="@<04><00><00><7f>;<00>", size=7)
2008/10/06 11:14:18.012 terminal StreamCore.cc:967: StreamCore::readCallback(DZ:ai) inputBuffer="U@<04><00><00><7f>;<00>", size 8
2008/10/06 11:14:18.012 terminal StreamCore.cc:999: StreamCore::readCallback(DZ:ai) end flag received
2008/10/06 11:14:18.013 terminal StreamCore.cc:1056: StreamCore::readCallback(DZ:ai) input line: "U@<04><00><00><7f>;<00>"
2008/10/06 11:14:18.013 terminal StreamEpics.cc:504: streamScanfN(DZ:ai,format=%R,maxStringSize=40)
2008/10/06 11:14:18.013 terminal StreamCore.cc:1345: StreamCore::scanValue(DZ:ai, format=%R, double) input="<00><00><7f>;<00>"
2008/10/06 11:14:18.013 terminal StreamCore.cc:1349: StreamCore::scanValue(DZ:ai) scanned 0.00389099
2008/10/06 11:14:18.013 terminal StreamEpics.cc:513: streamScanfN(DZ:ai) success, value=""  

======= At 2008-10-04, 23:25:45 you wrote: =======

>The input string is a zero-length string, which is OK, but the char
>*value pointer is NULL for some reason, so when it tries to copy \0 to
>value[0] you are getting an access violation.  I don't know why
>value=NULL.
>
>Mark
>
>
>-----Original Message-----
>From: [email protected]
>[mailto:[email protected]] On Behalf Of Silver
>Sent: Saturday, October 04, 2008 10:18 AM
>To: tech-talk
>Subject: Re: RE: problem of using Streamdevice
>
>hi, Mark:
>
>  Following is gdb message, could you give me some hints?
>
>Core was generated by `O.linux-x86/streamApp example.cmd'.
>Program terminated with signal 11, Segmentation fault.
>#0  StdStringConverter::scanString (this=0x9268b8, fmt=@0xb7f6aa14,
>input=0x88d2deb "", value=0x0, maxlen=0)
>    at ../StreamFormatConverter.cc:281
>281             value[0] = '\0';
>(gdb) bt
>#0  StdStringConverter::scanString (this=0x9268b8, fmt=@0xb7f6aa14,
>input=0x88d2deb "", value=0x0, maxlen=0)
>    at ../StreamFormatConverter.cc:281
>#1  0x00913ce0 in StreamCore::matchInput (this=0x88d29e0) at
>../StreamCore.cc:1153
>#2  0x00916667 in StreamCore::readCallback (this=0x88d29e0,
>status=StreamIoEnd, input=0x88d384c, size=7) at ../StreamCore.cc:1058
>#3  0x008ffc6b in AsynDriverInterface::readHandler (this=0x88d37d8) at
>../StreamBusInterface.h:112
>#4  0x0090100a in handleRequest (pasynUser=0x88ce90c) at
>../AsynDriverInterface.cc:1304
>#5  0x009509d3 in portThread (pport=0x888fb68) at
>../../asyn/asynDriver/asynManager.c:779
>#6  0x0064783f in start_routine (arg=0x88ce590) at
>../../../src/libCom/osi/os/posix/osdThread.c:320
>#7  0x0084b322 in start_thread () from
>/lib/i686/nosegneg/libpthread.so.0
>#8  0x0028a9ce in clone () from /lib/i686/nosegneg/libc.so.6
>(gdb)   
>
>here is some parts of StreamFormatConverter.cc:
>int StdStringConverter::
>
>scanString(const StreamFormat& fmt, const char* input,
>
>    char* value, size_t maxlen)
>
>{
>
>    int length = -1;
>
>    if (*input == '\0')
>
>    {
>
>        // match empty string
>
>        value[0] = '\0';
>
>        return 0;
>
>    }
>
>    if (fmt.flags & skip_flag)
>
>    {
>
>        /* can't use return value on vxWorks: sscanf with %* format
>
>           returns -1 at end of string whether value is found or not */
>
>        sscanf(input, fmt.info, &length);
>
>    }
>
>
>
>======= At 2008-10-04, 20:04:51 you wrote: =======
>
>>You should enable core dumps and then run gdb on the core file to see
>where it crashed.
>>
>>________________________________
>>
>>From: [email protected] on behalf of Silver
>>Sent: Sat 10/4/2008 6:31 AM
>>To: tech-talk
>>Subject: problem of using Streamdevice
>>
>>
>>
>>hi, all:
>>
>>  I am just trying Streamdevice driver by Franksen's suggestion. Seems
>it's a flexabile way of controlling our equipment. But I met some
>problems, the ioc will crash quickly after starting.
>>our streamdevice will response 8 bytes data in hex like "55 40 04 67 39
>47 be e3". in the data, "67 39 47 be" is a float's 4 bytes. other bytes
>are header or crc. So I write my protocol file like this:
>># example stream protocol file
>>
>>#Terminator = CR LF;
>>ReplyTimeout = 80000; # 10 sec is very long, for keyboard input
>>ReadTimeout = 1000;   # also long for keyboard input
>>
>># note the field access
>>ai {
>>    out 170 129 00 129;
>>    in "%*3c%#R%*1c";  
>>}
>>
>>my example.db is like this:
>># process this record to reload all stream protocols
>>
>>record (ai, "$(PREFIX):ai")
>>{
>>    field (DESC, "An analog value")
>>    field (DTYP, "stream")
>>    field (INP,  "@example.proto ai terminal")
>>#    field (PREC, "3")
>>#    field (LINR, "LINEAR")
>>#    field (ESLO, "0.000305180437934") # 20.0 / 0xffff
>>#    field (EOFF, "-10")
>>#    field (EGU,  "V")
>>    field (SCAN, ".5 second")
>>}
>>
>>my example.cmd file:
>>#!./streamApp
>>
>>dbLoadDatabase "O.Common/streamApp.dbd"
>>streamApp_registerRecordDeviceDriver
>>
>>#where can protocols be located?
>>epicsEnvSet "STREAM_PROTOCOL_PATH", ".:protocols:../protocols/"
>>
>>#setup the busses
>>
>>#example telnet style IP port setup
>>drvAsynIPPortConfigure "terminal", "192.168.1.103:4001"
>>#asynOctetSetInputEos "terminal",0,"\r\n"
>>#asynOctetSetOutputEos "terminal",0,"\r\n"
>>
>>#example VXI11 (GPIB via IP) port setup
>>#vxi11Configure "GPIB","ins023",1,5.0,"hpib"
>>
>>#load the records
>>dbLoadRecords "example.db","PREFIX=DZ"
>>#dbLoadRecords "scalcout.db","PREFIX=DZ"
>>
>>#lots! of debug output
>>#var streamDebug 1
>>
>>#enable debug output
>>var streamDebug 1
>>
>>iocInit
>>
>>my output of running ioc:
>>
>>~/testApp/StreamDevice-2-4/streamApp>./example.cmd
>>#!./streamApp
>>dbLoadDatabase "O.Common/streamApp.dbd"
>>streamApp_registerRecordDeviceDriver
>>#where can protocols be located?
>>epicsEnvSet "STREAM_PROTOCOL_PATH", ".:protocols:../protocols/"
>>#setup the busses
>>#example telnet style IP port setup
>>drvAsynIPPortConfigure "terminal", "192.168.1.103:4001"
>>#asynOctetSetInputEos "terminal",0,"\r\n"
>>#asynOctetSetOutputEos "terminal",0,"\r\n"
>>#example VXI11 (GPIB via IP) port setup
>>#vxi11Configure "GPIB","ins023",1,5.0,"hpib"
>>#load the records
>>dbLoadRecords "example.db","PREFIX=DZ"
>>#dbLoadRecords "scalcout.db","PREFIX=DZ"
>>#lots! of debug output
>>#var streamDebug 1
>>iocInit
>>Starting iocInit
>>#######################################################################
>#####
>>###  EPICS IOC CORE built on Sep 27 2008
>>###  EPICS R3.14.8.2 $R3-14-8-2$ $2006/01/06 15:55:13$
>>#######################################################################
>#####
>>iocInit: All initialization complete
>>#enable debug output
>>var streamDebug 1
>>epics>
>>epics> dbpr DZ:ai
>>ASG:                DESC: An analog value                   DISA: 0
>
>>DISP: 0             DISV: 1             NAME: DZ:ai         RVAL: 0
>
>>SEVR: NO_ALARM      STAT: NO_ALARM      SVAL: 0             TPRO: 0
>
>>VAL: 0.003662109375
>>epics> Segmentation fault
>>:~/testApp/StreamDevice-2-4/streamApp>
>>
>>Can anybody give me some hints? thanks a lot for your help.
>>
>>Best regards
>>Geyang  2008-10-04
>
>= = = = = = = = = = = = = = = = = = = =
>			
>
>Best regards				 
>Geyang 2008-10-04

= = = = = = = = = = = = = = = = = = = =
			

Best regards				 
Geyang 2008-10-06




References:
RE: RE: problem of using Streamdevice Mark Rivers

Navigate by Date:
Prev: RE: RE: problem of using Streamdevice Mark Rivers
Next: Process 'bo' on write? David Dudley
Index: 1994  1995  1996  1997  1998  1999  2000  2001  2002  2003  2004  2005  2006  2007  <20082009  2010  2011  2012  2013  2014  2015  2016  2017  2018  2019  2020  2021  2022  2023  2024 
Navigate by Thread:
Prev: RE: RE: problem of using Streamdevice Mark Rivers
Next: Re: problem of using Streamdevice Dirk Zimoch
Index: 1994  1995  1996  1997  1998  1999  2000  2001  2002  2003  2004  2005  2006  2007  <20082009  2010  2011  2012  2013  2014  2015  2016  2017  2018  2019  2020  2021  2022  2023  2024 
ANJ, 02 Sep 2010 Valid HTML 4.01! · Home · News · About · Base · Modules · Extensions · Distributions · Download ·
· Search · EPICS V4 · IRMIS · Talk · Bugs · Documents · Links · Licensing ·