Skip to content

Instantly share code, notes, and snippets.

@frondeus
Created March 18, 2020 10:06
Show Gist options
  • Star 0 You must be signed in to star a gist
  • Fork 0 You must be signed in to fork a gist
  • Save frondeus/2632e36add2a2e76f86570df257588e4 to your computer and use it in GitHub Desktop.
Save frondeus/2632e36add2a2e76f86570df257588e4 to your computer and use it in GitHub Desktop.
Lapin 0.33.0 connection error logs
Running `target/debug/examples/simple`
[2020-03-18T09:55:50Z TRACE amqp_client::client] connect(amqp://127.0.0.1:5672/%2f)
[2020-03-18T09:55:50Z INFO amqp_client::client] Connecting to AMQP server 'amqp://127.0.0.1:5672/%2f' ...
[2020-03-18T09:55:50Z TRACE amq_protocol_tcp] Connecting to 127.0.0.1:5672
[2020-03-18T09:55:50Z TRACE amq_protocol_tcp] Registering for mio events
[2020-03-18T09:55:50Z TRACE mio::poll] registering event source with poller: token=Token(1), interests=READABLE | WRITABLE
[2020-03-18T09:55:50Z DEBUG lapin::channels] create channel with id 0
[2020-03-18T09:55:50Z TRACE lapin::connection] connection send_frame; channel_id=0
[2020-03-18T09:55:50Z TRACE lapin::connection] connection wake
[2020-03-18T09:55:50Z TRACE mio::poll] reregistering event source with poller: token=Token(1), interests=READABLE | WRITABLE
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run; can_read=false, can_write=true, has_data=true
[2020-03-18T09:55:50Z TRACE lapin::io_loop] will write to buffer: ProtocolHeader
[2020-03-18T09:55:50Z TRACE lapin::io_loop] wrote 8 bytes
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop send continue; can_read=false, can_write=true, has_data=true
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run done; can_read=false, can_write=true, has_data=true, status=Initial
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run; can_read=false, can_write=true, has_data=true
[2020-03-18T09:55:50Z TRACE lapin::io_loop] wrote 0 bytes
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run done; can_read=false, can_write=true, has_data=false, status=Initial
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:55:50Z TRACE lapin::io_loop] read 502 bytes
[2020-03-18T09:55:50Z TRACE lapin::connection] will handle frame: Method(0, Connection(Start(Start { version_major: 0, version_minor: 9, server_properties: FieldTable({ShortString("capabilities"): FieldTable(FieldTable({ShortString("authentication_failure_close"): Boolean(true), ShortString("basic.nack"): Boolean(true), ShortString("connection.blocked"): Boolean(true), ShortString("consumer_cancel_notify"): Boolean(true), ShortString("consumer_priorities"): Boolean(true), ShortString("direct_reply_to"): Boolean(true), ShortString("exchange_exchange_bindings"): Boolean(true), ShortString("per_consumer_qos"): Boolean(true), ShortString("publisher_confirms"): Boolean(true)})), ShortString("cluster_name"): LongString(LongString("rabbit@891e65bd2c25")), ShortString("copyright"): LongString(LongString("Copyright (C) 2007-2019 Pivotal Software, Inc.")), ShortString("information"): LongString(LongString("Licensed under the MPL. See https://www.rabbitmq.com/")), ShortString("platform"): LongString(LongString("Erlang/OTP 22.0.7")), ShortString("product"): LongString(LongString("RabbitMQ")), ShortString("version"): LongString(LongString("3.7.17"))}), mechanisms: LongString("AMQPLAIN PLAIN"), locales: LongString("en_US") })))
[2020-03-18T09:55:50Z TRACE lapin::channel] Server sent connection::Start: Start { version_major: 0, version_minor: 9, server_properties: FieldTable({ShortString("capabilities"): FieldTable(FieldTable({ShortString("authentication_failure_close"): Boolean(true), ShortString("basic.nack"): Boolean(true), ShortString("connection.blocked"): Boolean(true), ShortString("consumer_cancel_notify"): Boolean(true), ShortString("consumer_priorities"): Boolean(true), ShortString("direct_reply_to"): Boolean(true), ShortString("exchange_exchange_bindings"): Boolean(true), ShortString("per_consumer_qos"): Boolean(true), ShortString("publisher_confirms"): Boolean(true)})), ShortString("cluster_name"): LongString(LongString("rabbit@891e65bd2c25")), ShortString("copyright"): LongString(LongString("Copyright (C) 2007-2019 Pivotal Software, Inc.")), ShortString("information"): LongString(LongString("Licensed under the MPL. See https://www.rabbitmq.com/")), ShortString("platform"): LongString(LongString("Erlang/OTP 22.0.7")), ShortString("product"): LongString(LongString("RabbitMQ")), ShortString("version"): LongString(LongString("3.7.17"))}), mechanisms: LongString("AMQPLAIN PLAIN"), locales: LongString("en_US") }
[2020-03-18T09:55:50Z TRACE lapin::connection] connection send_frame; channel_id=0
[2020-03-18T09:55:50Z TRACE lapin::connection] connection wake
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop do_run done; can_read=false, can_write=true, has_data=false, status=Initial
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:55:50Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] read 0 bytes
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop send continue; can_read=true, can_write=true, has_data=false
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run done; can_read=true, can_write=true, has_data=false, status=Initial
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop run
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop poll done
[2020-03-18T09:56:00Z TRACE lapin::io_loop] io_loop do_run; can_read=true, can_write=true, has_data=false
# After that it repeats last logs in lines 129:135 forever
@Keruspe
Copy link

Keruspe commented Mar 18, 2020

Ok, so the problem here is that has_data is false after replying to Conection.Start. That's really odd, let me check

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment