qpid-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From "Duong Quynh (FSU1.Z8.IP)" <Quyn...@fsoft.com.vn>
Subject RE: Potential qpid cpp 0.28 bug
Date Tue, 08 Jul 2014 04:08:25 GMT
Here's your log Gordon.

./amq-producer 
2014-07-08 11:06:27 [Messaging] debug Driver started
2014-07-08 11:06:27 [Messaging] info Starting connection to amqp:tcp:localhost:5672
2014-07-08 11:06:27 [Messaging] info Connecting to tcp:localhost:5672
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Connecting ...
2014-07-08 11:06:27 [System] info Connecting: [::1]:5672
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 1 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 1 || 0
2014-07-08 11:06:27 [Protocol] debug tcp:localhost:5672 writing protocol header: 1-0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::encode(65535): 8
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Connected
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Waiting to be authenticated...
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Protocol] debug tcp:localhost:5672 read protocol header: 1-0
2014-07-08 11:06:27 [Security] trace Reading SASL frame of size 34
2014-07-08 11:06:27 [Security] trace Reading SASL-MECHANISMS
2014-07-08 11:06:27 [Protocol] debug tcp:localhost:5672 Received SASL-MECHANISMS(ANONYMOUS
PLAIN )
2014-07-08 11:06:27 [Security] debug CyrusSasl::start(PLAIN )
2014-07-08 11:06:27 [Security] debug min_ssf: 0, max_ssf: 256
2014-07-08 11:06:27 [Security] debug getUserFromSettings(): guest
2014-07-08 11:06:27 [Security] debug CyrusSasl::start(PLAIN ): selected PLAIN response: '\x00guest\x00guest'
2014-07-08 11:06:27 [Security] trace Completed encoding of frame of 52 bytes
2014-07-08 11:06:27 [Protocol] debug tcp:localhost:5672 Sent SASL-INIT(PLAIN, \x00guest\x00guest,
localhost)
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::decode(42): 42
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 1
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 1
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::encode(65535): 52
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace Reading SASL frame of size 16
2014-07-08 11:06:27 [Security] trace Reading SASL-OUTCOME
2014-07-08 11:06:27 [Protocol] debug tcp:localhost:5672 Received SASL-OUTCOME(\x00)
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::decode(16): 16
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]:   -> AMQP
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 encoded 8 bytes from 65535
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 decode(8)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]:   <- AMQP
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 decoded 8 bytes from 8
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Authenticated
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Opening...
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 -> @open(16)
[container-id="4cc71475-8b5c-4127-b630-7d02c05da20b", properties={:"qpid.client_process"="amq-producer",
:"qpid.client_pid"=32132, :"qpid.client_ppid"=20140}]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 encoded 155 bytes from 65535
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 decode(23)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @open(16)
[container-id="", hostname="", max-frame-size=1048576]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 decoded 23 bytes from 23
2014-07-08 11:06:27 [Messaging] debug tcp:localhost:5672 Opened
2014-07-08 11:06:27 [Messaging] info Connected to tcp:localhost:5672
1
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 -> @begin(17)
[next-outgoing-id=0, incoming-window=2147483647, outgoing-window=0]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 encoded 30 bytes from 65535
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 decode(26)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @begin(17)
[remote-channel=0, next-outgoing-id=1, incoming-window=0, outgoing-window=0, handle-max=1024]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 decoded 26 bytes from 26
2014-07-08 11:06:27 [Messaging] debug Link attach sent for 0x142eb00, state=10
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Messaging] debug Waiting for confirmation of link attach for 0x142eb00,
state=10...
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 -> @attach(18)
[name="topic://usa.news_9e51194a-fa15-4c62-8291-b4728877e2c3", handle=0, role=false, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="topic://usa.news", durable=0, timeout=0, dynamic=false],
target=@target(41) [address="topic://usa.news", durable=0, timeout=0, dynamic=false], initial-delivery-count=0]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 encoded 166 bytes from 65535
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 decode(147)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @attach(18)
[name="topic://usa.news_9e51194a-fa15-4c62-8291-b4728877e2c3", handle=0, role=true, snd-settle-mode=2,
rcv-settle-mode=0, source=@source(40) [address="topic://usa.news"], target=@target(41) [address="topic://usa.news"]]
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @flow(19)
[next-incoming-id=0, incoming-window=1, next-outgoing-id=1, outgoing-window=0, handle=0, delivery-count=0,
link-credit=100]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 decoded 147 bytes from 147
2014-07-08 11:06:27 [Messaging] debug Attach succeeded to topic://usa.news
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 0
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 1
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 2
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 3
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 4
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 5
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 6
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 7
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 8
2014-07-08 11:06:27 [Messaging] debug Sending message, buffer is 59 bytes
2014-07-08 11:06:27 [Messaging] debug Trimming buffer from 59 to 31
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
Sent - Hello news! 9
2
2014-07-08 11:06:27 [Messaging] debug wakeupDriver()
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 -> @transfer(20)
[handle=0, delivery-id=0, delivery-tag=b"\x00\x00\x00\x00", message-format=0, settled=false,
more=false] (31) "\x00Sp\xc0\x04\x02BP\x00\x00St\xc1\x01\x00\x00Su\xa0\x0bHello news!"
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 encoded 65 bytes from 65535
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 encode(65535)
2014-07-08 11:06:27 [Security] trace tcp:localhost:5672 Sasl::canEncode(): 0 || 0
2014-07-08 11:06:27 [Messaging] trace tcp:localhost:5672 decode(48)
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @flow(19)
[next-incoming-id=1, incoming-window=1, next-outgoing-id=1, outgoing-window=0, handle=0, delivery-count=1,
link-credit=100]
2014-07-08 11:06:27 [Protocol] trace [4cc71475-8b5c-4127-b630-7d02c05da20b]: 0 <- @disposition(21)
[role=true, first=0, last=0, settled=true, state=@accepted(36) []]
2014-07-08 11:06:27 [Network] debug tcp:localhost:5672 decoded 48 bytes from 48

<hangs here>

-----Original Message-----
From: Gordon Sim [mailto:gsim@redhat.com] 
Sent: Monday, July 07, 2014 4:55 PM
To: dev@qpid.apache.org
Subject: Re: Potential qpid cpp 0.28 bug

On 07/07/2014 10:46 AM, Duong Quynh (FSU1.Z8.IP) wrote:
> Hi Gordon,
>
> I have made the changes to ConnectionContext.cpp file, make clean, make and make install
again, deleted the executable binary, rebuilt, problem still persists:

Can you get a full log, QPID_LOG_ENABLE=trace+ (i.e. not just protocol)?


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@qpid.apache.org For additional commands, e-mail: dev-help@qpid.apache.org


---------------------------------------------------------------------
To unsubscribe, e-mail: dev-unsubscribe@qpid.apache.org
For additional commands, e-mail: dev-help@qpid.apache.org


Mime
View raw message