Menu
▾
▴
Re: [hpoj-devel] hpo bug (sane 1.0.4)
|
From: Obi-Wan <bv...@in...> - 2001-06-01 07:39:14
|
>> and this in the ptal-mlcd log file:
>>
>> ---------
>> ptal-mlcd: ERROR at transport/ExMlcCommandChannel.cpp:186,
>> dev=<par:psc500> , pid=12720, errno=11
>> Reply timer popped on port=0, count=1!
>>
>> ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500> ,
>> pid=12720, errno=11
>> exClose(reason=0x3002)
>
> That's not good. :-) Occasionally I've managed to get the R series and PSC
> 500 into a bad state, where the LCD scrolls the message "turn the power off
> and back on again". I wonder if that's what happened here? But in that
> case I would have expected a different error. Anyway, I'm just thinking
> aloud here.
LCD looked fine when I got home.
> Another thing that comes to mind is to make sure that your parallel port is
> configured in your BIOS setup for preferably ECP, or PS/2 (aka BPP)
> otherwise, but not EPP. Also, make sure you're using a good parallel cable
> and that the device is connected directly to your computer without any
> switchboxes or other pass-through devices, such as Zip drives.
Good cable. No pass-through devices. I'll check the BIOS next time
the system is down.
> Here's another thing you can try, but probably not until you get home and
> make sure the device is in a good state. Add the "-nofork" switch to
> ptal-mlcd, and it will give you a debug console. Use the "log" command to
> enable debug logging ("nolog" turns off the debug messages). Then use the
> "activate" command to make it start trying to talk to the peripheral. When
> the messages stop spewing, use the "dump" command, which displays the state
> of the internal data structures. Wait about 20 seconds to see if you get
> the "reply timer popped" message. If that doesn't happen, then in another
> window try ptal-devid (this should work if you've gotten this far). After
> this point try printing and scanning (separately) and see what happens. You
> can press control-C to kill ptal-mlcd.
On my list...
I did make a fresh reboot of my system & the PSC500 earlier this evening
(before I read your message about checking the BIOS). Without checking
anything, I printed a few pages & it worked fine. No errors in my stdout
logs. I brought up xsane to test that. Grabbing a preview worked, but
was dreadfully slow. Note that I'm on a P90 & at the same time I was
listening to a RealAudio station & actively web browsing. Still, it
seemed slower than it should have been. Attempting to scan a color image
to disk at 150 dpi cranked away for a good long while, then flagged an
xsane error window (something related to opening the output file), and
the shell said this:
> xsane
ptalChannelOpen(chan=0x080E6500): provider failed open!
ptalMlcChannelOpen(chan=0x080E6500): read(openReply) returns 0!
ptalChannelOpen(chan=0x080E6500): provider failed open!
The stdout log then got gobs of output, which I've included below.
--
Ben "Obi-Wan" Hollingsworth ob...@je...
The stuff of earth competes for the allegiance I owe only to the
Giver of all good things, so if I stand, let me stand on the
promise that You will pull me through. -- Rich Mullins
------------------------- Achilles tendon - cut here -------------------------
ptal-mlcd: ERROR at ParPort.cpp:163, dev=<par:psc500>, pid=139, errno=11
statusWaitSetClear(event=23) timed out!
ptal-mlcd: ERROR at ExMgr.cpp:2131, dev=<par:psc500>, pid=139, errno=11
llioService: llioForwardToReverse failed!
ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500>, pid=139, errno=11
exClose(reason=0x0010)
ptal-mlcd: ERROR at ParPort.cpp:163, dev=<par:psc500>, pid=139, errno=11
statusWaitSetClear(event=23) timed out!
ptal-mlcd: ERROR at ExMgr.cpp:2131, dev=<par:psc500>, pid=139, errno=11
llioService: llioReverseToForward failed!
ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500>, pid=139, errno=11
exClose(reason=0x0010)
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1059, dev=<par:psc500>, pid=139, errno=11
reverseDataReceived_ts(port=0,channel=2): not open!
ptal-mlcd: ERROR at transport/ExMlcCommandChannel.cpp:1632, dev=<par:psc500>, pid=139, errno=11
sendError(port=0,MLC): error=0x07.
ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500>, pid=139, errno=11
exClose(reason=0x3007)
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1059, dev=<par:psc500>, pid=139, errno=11
reverseDataReceived_ts(port=0,channel=2): not open!
ptal-mlcd: ERROR at transport/ExMlcCommandChannel.cpp:1632, dev=<par:psc500>, pid=139, errno=11
sendError(port=0,MLC): error=0x07.
ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500>, pid=139, errno=11
exClose(reason=0x3007)
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1059, dev=<par:psc500>, pid=139, errno=11
reverseDataReceived_ts(port=0,channel=2): not open!
ptal-mlcd: ERROR at transport/ExMlcCommandChannel.cpp:1632, dev=<par:psc500>, pid=139, errno=11
sendError(port=0,MLC): error=0x07.
ptal-mlcd: ERROR at ExMgr.cpp:872, dev=<par:psc500>, pid=139, errno=11
exClose(reason=0x3007)
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1621, dev=<par:psc500>, pid=139, errno=11
handleInitReply(port=0): ignoring extra InitReply!
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1621, dev=<par:psc500>, pid=139, errno=11
handleInitReply(port=0): ignoring extra InitReply!
ptal-mlcd: ERROR at transport/ExMlcTransport.cpp:1621, dev=<par:psc500>, pid=139, errno=11
handleInitReply(port=0): ignoring extra InitReply!
************************************************
************************************************
************************************************
ExMgr:
gDebugFlag=0
pBufferPool: available#=10, size=4102, requested#=230, allocated#=12
initialized=1
nofork=0
activateAtStartup=0
argc=0
fd=3: r=1, w=0, x=0
fd=6: r=1, w=0, x=0
fdCount=7
exState=2
exActivateCount=6
exCloseCount=5
exCloseReason=0x3007
exReactivateFlag=0
noDot4=0
enablePmlMultiplexing=1
sleepBeforeOpen=0
tryDot4=0
tryMlc=1
miser=0
pFreeMsgPool: depth=2
pPendingMsgQueue: depth=0
pActiveTimerQueue: depth=1
pPeriodicTimerQueue: depth=1
select timeout: sec=0, usec=50000, infinite=0
consoleAllowRemote=0
consoleOldStdin=4
consoleOldStdout=5
consoleIsRemote=0
socketSuffix=<par:psc500>
socketAliasSuffix=<mlcpp0>
socketName=</dev/ptal-mlcd/par:psc500>
socketAliasName=</dev/ptal-mlcd/mlcpp0>
socketFd=3
pmlTransportSession=8
pmlCurrentSession=-1
pmlLastSession=31
sessionInWrite=0
llioName=<>
llioOverrideDeviceID=<>
llioFd=-1
llioDummyFd=-1
llioPollState=0
llioLastHitTime=991380275
llioForwardBdrQueue: depth=0
llioDeviceID=<MFG:HEWLETT-PACKARD;MDL:PSC 500;CMD:MLC,PCL,PML,SCL;CLS:PRINTER;DES:Hewlett-Packard PSC 500;CMT:OFFICEJET PRO;SERN:SGH02EGRMLWZ;VSTATUS:$HB0$FC0,ff,DN,IDLE,CUT;LSS:01;LDF:1;LDE:1;>
baseLow=0x378
baseHigh=0x778
portType=0x000
ParPort:
portType=1
debug=0
setupDelay(0)={tv_sec=0,tv_usec=100}
strobeDelay(1)={tv_sec=0,tv_usec=0}
holdDelay(2)={tv_sec=0,tv_usec=100}
ecpSetupDelay(3)={tv_sec=0,tv_usec=0}
ecpPostHtrDelay(4)={tv_sec=0,tv_usec=10000}
signalTimeout(5)={tv_sec=0,tv_usec=100000}
busyTimeout(6)={tv_sec=1,tv_usec=0}
reversePollRate(7)={tv_sec=0,tv_usec=10000}
dead=0
currentMode=0x10
currentChannel=0
htrCount=4
forwardMode=0x10
reverseMode=0x00
channel=77
status =0x7F
control =0xED
ECP config A =0x00
ECP config B =0x00
ECP control =0xE6
Transport:
this=0x08067838
port=0
pMgr=0x08066CB8
pPhysicalPort=0x08066CB8
forwardTransactionCounter: current=16, initial=16, low=15, high=16, max=16
channelCount=17
channelArray=0x08067A28
channelArray[0]=0x08067A70
channelArray[1]=0x08067D68
channelArray[2]=0x08067F68
channelArray[3]=0x08068168
channelArray[4]=0x08068368
channelArray[5]=0x08068568
channelArray[6]=0x08068768
channelArray[7]=0x08068968
channelArray[8]=0x08068B68
channelArray[9]=0x08068D68
channelArray[10]=0x08068F68
channelArray[11]=0x08069168
channelArray[12]=0x08069368
channelArray[13]=0x08069568
channelArray[14]=0x08069768
channelArray[15]=0x08069968
channelArray[16]=0x08069B68
overheadBufferCount=0
pNextTransport=0x00000000
pForwardDataTimer=0x08067900 (count=0)
pForwardDataTimeoutMsg=0x080678D0
reverseDataBufferCount=180
reverseDataBufferSize=4102
maxForwardBdrsPerTransaction=4
nextChannelToAllocate=17
nextBlockedChannel=-1
lookupQueue: depth=0, peek=0x00000000
----------------
pForwardHeaderPool=0x08067940
mlcChannelArray=0x08067A28
pCommandChannel=0x08067A70
grcState=0
reverseDataStopped=0
tryDot4=0
tryPreDot4=0
tryMlc=1
requestedRevision=0x03
revision=0x03
maxRemoteSockets=32
remsock[0]: state=2, socketID=1, fwdPS=134, revPS=134
Command channel:
this=0x08067A70
port=0
channel=0
localSocket=0
pTransport=0x08067838
pMgr=0x08066CB8
pPhysicalPort=0x08066CB8
openingAnySetFlags=0x0010
closingAnySetFlags=0x0124
flags=0x0003
countOpen=0
countOpenFailure=0
pService=0x00000000
scd=0x00000000
forwardDataPriority=0
minBuffersRequired=0
benefitOfMoreBuffers=0
reverseDataBufferSize=4102
bufferCount=3
remoteSocket=0
maxForwardDatalen=58
maxReverseDatalen=58
countForwardData=4
countReverseData=7
countReverseBuffers=7
countReverseBufferReturns=2
lastReverseBuffer=0x00000000
currentForwardBuffer=0x00000000
currentGrabbedCredit=-1
currentGrabbedTransaction=-1
----------------
pMlcTransport=0x08067838
pCommandChannel=0x08067A70
disableCreditCommands=1
pForwardCreditRequestTimer=0x08067C08
pForwardCreditRequestMsg=0x08067BD8
pReverseCreditHeartbeatTimer=0x08067C70
pReverseCreditHeartbeatMsg=0x08067C40
musherFirstCreditRequestDelay=2
musherNextCreditRequestDelay=5
gusherFirstCreditRequestDelay=5
gusherNextCreditRequestDelay=10
miserFirstCreditRequestDelay=0
miserNextCreditRequestDelay=2
gusherPiggybackCreditCount=1
gusherCreditCount=2
miserCreditRequestCount=2
workaroundReverseCreditLoss=0
maxForwardPacketSize=64
maxReversePacketSize=64
forwardCreditRequest=16
forwardMaxOutstandingCredit=1
forwardCredit: current=1, initial=1, low=0, high=1, max=65535
lastCreditRequestGotUsNowhere=0
reverseMaxOutstandingCredit=1
reverseBuffersPerPacket=1
uncreditedBuffers=0
reverseCreditToGrant=1
reverseCredit: current=1, initial=1, low=1, high=2, max=65535
countSendPiggybackCredit=4
countHandlePiggybackCredit=7
countSendCredit=0
countHandleCredit=0
countHandleCreditAfterCreditRequest=0
countSendCreditRequest=0
countHandleCreditRequest=0
countSendEmptyCreditRequestReply=0
countHandleEmptyCreditRequestReply=0
isGusher=1
----------------
pForwardCommandPool=0x08067CA8
pForwardNonconsumingQueue=0x08067CC8
pForwardReplyQueue=0x08067CD8
pForwardRequestQueue=0x08067CE8
forwardRequestCredit: current=1, initial=1, low=0, high=1, max=65535
pCommandReplyTimer=0x08067D28 (count=1)
pCommandReplyTimeoutMsg=0x08067CF8
allowErrorPackets=1
lastPsid=0
lastSsid=0
Session 0: type=command
state=4
fd=6
scdlink=9
pLookup=0x08067368
outstandingForwardBdrCount=2
pReverseBdrQueue: depth=0
tcd=0x00000000
pCommandBdr=0x080732A8
pmlTrapsRegistered=0
Session 8: type=transport
state=6
fd=-1
scdlink=-1
pLookup=0x08067668
outstandingForwardBdrCount=0
pReverseBdrQueue: depth=0
tcd=0x08067D68
pCommandBdr=0x00000000
pmlTrapsRegistered=0
Transport channel for session 8:
this=0x08067D68
port=0
channel=1
localSocket=128
pTransport=0x08067838
pMgr=0x08066CB8
pPhysicalPort=0x08066CB8
openingAnySetFlags=0x0010
closingAnySetFlags=0x0124
flags=0x0003
countOpen=1
countOpenFailure=0
pService=0x08066CB8
scd=0x00000008
forwardDataPriority=0
minBuffersRequired=10
benefitOfMoreBuffers=1
reverseDataBufferSize=4102
bufferCount=12
remoteSocket=1
maxForwardDatalen=128
maxReverseDatalen=128
countForwardData=0
countReverseData=0
countReverseBuffers=0
countReverseBufferReturns=0
lastReverseBuffer=0x00000000
currentForwardBuffer=0x00000000
currentGrabbedCredit=-1
currentGrabbedTransaction=-1
----------------
pMlcTransport=0x08067838
pCommandChannel=0x08067A70
disableCreditCommands=0
pForwardCreditRequestTimer=0x08067EC8
pForwardCreditRequestMsg=0x08067E98
pReverseCreditHeartbeatTimer=0x08067F30
pReverseCreditHeartbeatMsg=0x08067F00
musherFirstCreditRequestDelay=2
musherNextCreditRequestDelay=5
gusherFirstCreditRequestDelay=5
gusherNextCreditRequestDelay=10
miserFirstCreditRequestDelay=0
miserNextCreditRequestDelay=2
gusherPiggybackCreditCount=1
gusherCreditCount=2
miserCreditRequestCount=2
workaroundReverseCreditLoss=0
maxForwardPacketSize=134
maxReversePacketSize=134
forwardCreditRequest=16
forwardMaxOutstandingCredit=65535
forwardCredit: current=4, initial=4, low=4, high=4, max=65535
lastCreditRequestGotUsNowhere=0
reverseMaxOutstandingCredit=0
reverseBuffersPerPacket=1
uncreditedBuffers=0
reverseCreditToGrant=0
reverseCredit: current=12, initial=12, low=12, high=12, max=65535
countSendPiggybackCredit=0
countHandlePiggybackCredit=0
countSendCredit=0
countHandleCredit=0
countHandleCreditAfterCreditRequest=0
countSendCreditRequest=0
countHandleCreditRequest=0
countSendEmptyCreditRequestReply=0
countHandleEmptyCreditRequestReply=0
isGusher=-1
Session 9: type=transport
state=4
fd=-1
scdlink=0
pLookup=0x00000000
outstandingForwardBdrCount=0
pReverseBdrQueue: depth=0
tcd=0x08067F68
pCommandBdr=0x00000000
pmlTrapsRegistered=0
Transport channel for session 9:
this=0x08067F68
port=0
channel=2
localSocket=129
pTransport=0x08067838
pMgr=0x08066CB8
pPhysicalPort=0x08066CB8
openingAnySetFlags=0x0010
closingAnySetFlags=0x0124
flags=0x0001
countOpen=0
countOpenFailure=0
pService=0x08066CB8
scd=0x00000009
forwardDataPriority=0
minBuffersRequired=10
benefitOfMoreBuffers=1
reverseDataBufferSize=4102
bufferCount=11
remoteSocket=0
maxForwardDatalen=0
maxReverseDatalen=0
countForwardData=0
countReverseData=0
countReverseBuffers=0
countReverseBufferReturns=0
lastReverseBuffer=0x00000000
currentForwardBuffer=0x00000000
currentGrabbedCredit=-1
currentGrabbedTransaction=-1
----------------
pMlcTransport=0x08067838
pCommandChannel=0x08067A70
disableCreditCommands=0
pForwardCreditRequestTimer=0x080680C8
pForwardCreditRequestMsg=0x08068098
pReverseCreditHeartbeatTimer=0x08068130
pReverseCreditHeartbeatMsg=0x08068100
musherFirstCreditRequestDelay=2
musherNextCreditRequestDelay=5
gusherFirstCreditRequestDelay=5
gusherNextCreditRequestDelay=10
miserFirstCreditRequestDelay=0
miserNextCreditRequestDelay=2
gusherPiggybackCreditCount=1
gusherCreditCount=2
miserCreditRequestCount=2
workaroundReverseCreditLoss=0
maxForwardPacketSize=0
maxReversePacketSize=0
forwardCreditRequest=16
forwardMaxOutstandingCredit=65535
forwardCredit: current=0, initial=0, low=0, high=0, max=65535
lastCreditRequestGotUsNowhere=0
reverseMaxOutstandingCredit=65535
reverseBuffersPerPacket=0
uncreditedBuffers=11
reverseCreditToGrant=0
reverseCredit: current=0, initial=0, low=0, high=0, max=65535
countSendPiggybackCredit=0
countHandlePiggybackCredit=0
countSendCredit=0
countHandleCredit=0
countHandleCreditAfterCreditRequest=0
countSendCreditRequest=0
countHandleCreditRequest=0
countSendEmptyCreditRequestReply=0
countHandleEmptyCreditRequestReply=0
isGusher=-1
************************************************
ptal-mlcd: FATAL ERROR at transport/ExTransport.cpp:428, dev=<par:psc500>, pid=139, errno=11
|
