[rabbitmq-discuss] librabbitmq faults

[Matti Linnanvuori]

Hi!

I saw the following RabbitMQ server log entries when a Perl Net::RabbitMQ client got stuck in recv and did not receive messages that were sent to the queue that it is was consuming. The logging stopped and started again only later after ERROR REPORT, which seems to indicate some kind of error.

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
Stopping Rabbit

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
stopped TCP Listener on [::]:5672

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24489.0> from 127.0.0.1:36360

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24422.0> from 127.0.0.1:41128

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24586.0> from 127.0.0.1:40043

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24497.0> from 127.0.0.1:36366

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24560.0> from 127.0.0.1:36373

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24414.0> from 127.0.0.1:41127

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24430.0> from 127.0.0.1:41129

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24389.0> from 127.0.0.1:41118

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
closing TCP connection <0.24476.0> from 127.0.0.1:36352

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
    application: rabbit
    exited: stopped
    type: temporary

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
    application: mnesia
    exited: stopped
    type: temporary

=INFO REPORT==== 11-Apr-2012::10:38:47 ===
    application: os_mon
    exited: stopped


=INFO REPORT==== 11-Apr-2012::10:38:47 ===
Halting Erlang VM

=INFO REPORT==== 11-Apr-2012::10:38:52 ===
Limiting to approx 924 file handles (829 sockets)

=INFO REPORT==== 11-Apr-2012::10:38:53 ===
Memory limit set to 373MB of 933MB total.

=INFO REPORT==== 11-Apr-2012::10:38:53 ===
msg_store_transient: using rabbit_msg_store_ets_index to provide index

=INFO REPORT==== 11-Apr-2012::10:38:53 ===
msg_store_persistent: using rabbit_msg_store_ets_index to provide index

=INFO REPORT==== 11-Apr-2012::10:38:53 ===
started TCP Listener on [::]:5672

=INFO REPORT==== 11-Apr-2012::10:38:53 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40065
=INFO REPORT==== 11-Apr-2012::10:38:53 ===
starting TCP connection <0.221.0> from 127.0.0.1:40065

=INFO REPORT==== 11-Apr-2012::10:38:54 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40066

=INFO REPORT==== 11-Apr-2012::10:38:54 ===
starting TCP connection <0.235.0> from 127.0.0.1:40066

=INFO REPORT==== 11-Apr-2012::10:38:54 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40067

=INFO REPORT==== 11-Apr-2012::10:38:54 ===
starting TCP connection <0.239.0> from 127.0.0.1:40067

=ERROR REPORT==== 11-Apr-2012::10:38:54 ===
connection <0.235.0>, channel 1 - error:
{amqp_error,not_found,"no exchange 'exchange.pmc.email-in' in vhost '/'",
            'basic.publish'}

=WARNING REPORT==== 11-Apr-2012::10:39:01 ===
exception on TCP connection <0.235.0> from 127.0.0.1:40066
connection_closed_abruptly

=INFO REPORT==== 11-Apr-2012::10:39:01 ===
closing TCP connection <0.235.0> from 127.0.0.1:40066

=INFO REPORT==== 11-Apr-2012::10:39:02 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40068

=INFO REPORT==== 11-Apr-2012::10:39:02 ===
starting TCP connection <0.260.0> from 127.0.0.1:40068

=ERROR REPORT==== 11-Apr-2012::10:39:02 ===
connection <0.260.0>, channel 1 - error:
{amqp_error,not_found,"no exchange 'exchange.pmc.email-in' in vhost '/'",
            'basic.publish'}

=INFO REPORT==== 11-Apr-2012::10:39:08 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40072

=INFO REPORT==== 11-Apr-2012::10:39:08 ===
starting TCP connection <0.277.0> from 127.0.0.1:40072

=WARNING REPORT==== 11-Apr-2012::10:41:58 ===
exception on TCP connection <0.260.0> from 127.0.0.1:40068
connection_closed_abruptly

=INFO REPORT==== 11-Apr-2012::10:41:58 ===
closing TCP connection <0.260.0> from 127.0.0.1:40068

=INFO REPORT==== 11-Apr-2012::10:41:58 ===
accepted TCP connection on [::]:5672 from 127.0.0.1:40074

=INFO REPORT==== 11-Apr-2012::10:41:58 ===
starting TCP connection <0.361.0> from 127.0.0.1:40074