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:[email protected]]
Sent: Monday, July 07, 2014 4:55 PM
To: [email protected]
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: [email protected] For additional
commands, e-mail: [email protected]
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
