My cassandane tests break. Yours too?

rsto at paranoia.at rsto at paranoia.at
Tue Dec 1 05:22:41 EST 2015 

Hi,

my Cassandane tests on the master branch are failing since October 29
and I haven't yet sorted out the root cause.

Since somewhere around commit c3d8f3781071 [1], all test fail with with
issues revolving around notifyd. E.g. either writing to a bad file
descriptor of the notifyd socket...

Message from syslogd at debdev at Nov 10 11:39:51 ...
 rl exception: Cannot write to pipe: Bad file descriptor#012 at
 Cassandane/Unit/TestPlan.pm line 174

...or notifyd blasting messages like the following to syslog:

Dec  1 11:04:54 debdev ne
BAoIMTIzNDU2NzgECAgIAwcAAAAKBWVycm9yBgAAAHJlc3VsdAoRam1hcF9tdWx0aWNvbnRhY3QIAAAAdGVzdG5hbWUEAgAAAAARAAAAcGFyYW1ldGVyX3NldHRpbmcKDy90bXAvdURmNUFkWHJNdAcAAABsb2dmaWxlBsVwXVYAAAAACgAAA
[...]

Now, is it just me for whom the master Cassandane tests are broken? Or
is any of you also experiencing these issues?

Thanks,
Robert


[1]
https://git.cyrus.foundation/rCc3d8f37810716fd98f5f5412ba3cce1043af079d

Full log of one of the test runs:

=====> Cyrus::TestCase[302] ---------- BEGIN test_user_rename_dom
----------
=====> Cyrus::TestCase[158] Magic attribute AllowMoves sets config
allowusermoves = yes
=====> Cyrus::TestCase[158] Magic attribute VirtDomains sets config
virtdomains = userid
=====> Instance[862] start main instance for test test_user_rename_dom:
basedir /var/tmp/cass/101443B4
=====> Instance[361] Found binary ctl_cyrusdb in
/var/tmp/cyrus/usr/cyrus/sbin
=====> Instance[361] Found binary httpd in
/var/tmp/cyrus/usr/cyrus/libexec
=====> Instance[361] Found binary imapd in
/var/tmp/cyrus/usr/cyrus/libexec
=====> Instance[774] started saslauthd for /var/tmp/cass/101443B4 as
29039
=====> Instance[1613] opening socket /var/tmp/cass/101443B4/run/mux
=====> Instance[801] started notifyd for /var/tmp/cass/101443B4 as 29041
=====> Instance[361] Found binary master in
/var/tmp/cyrus/usr/cyrus/libexec
=====> Instance[1359] Running: "/var/tmp/cyrus/usr/cyrus/libexec/master"
"-C" "/var/tmp/cass/101443B4/conf/imapd.conf" "-l" "255" "-p"
"/var/tmp/cass/101443B4/run/master.pid" "-d" "-M"
"/var/tmp/cass/101443B4/conf/cyrus.conf"
=====> Instance[726] _start_master: waiting for PID file
=====> Instance[729] _start_master: PID file present and correct
=====> Instance[741] _start_master: PID waiting for services
=====> Daemon[356] is_listening: service http is listening on
127.0.0.1:9116
=====> Util::Wait[75] Waited 0.037413 sec for 127.0.0.1:9116 to be in
LISTEN state
=====> Daemon[356] is_listening: service imap is listening on
127.0.0.1:9117
=====> Instance[752] _start_master: all services listening
=====> Instance[828] create user cassandane
=====> Util::Socket[61] create_client_socket INET host=127.0.0.1
port=9117
Perl exception: IMAPTalk: IMAP Connection closed by other end:
Connection reset by peer
 at /usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line 4294.
	Mail::IMAPTalk::_fill_imap_read_buffer(Mail::IMAPTalk=HASH(0x42f4680),
	undef, 1) called at
	/usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line 4325
	Mail::IMAPTalk::_imap_socket_read_line(Mail::IMAPTalk=HASH(0x42f4680))
	called at /usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line
	4180
	Mail::IMAPTalk::_next_simple_atom(Mail::IMAPTalk=HASH(0x42f4680))
	called at /usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line
	3771
	Mail::IMAPTalk::_parse_response(Mail::IMAPTalk=HASH(0x42f4680),
	"", HASH(0x42eaf30)) called at
	/usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line 3516
	eval {...} called at
	/usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line 3510
	Mail::IMAPTalk::_imap_cmd(Mail::IMAPTalk=HASH(0x42f4680),
	"login", 0, "", "admin", HASH(0x42eab58)) called at
	/usr/local/share/perl/5.20.2/Mail/IMAPTalk.pm line 636
	Mail::IMAPTalk::login(Mail::IMAPTalk=HASH(0x42f4680), "admin",
	"testpw") called at Cassandane/IMAPMessageStore.pm line 108
	Cassandane::IMAPMessageStore::connect(Cassandane::IMAPMessageStore=HASH(0x42eaab0))
	called at Cassandane/IMAPMessageStore.pm line 285
	Cassandane::IMAPMessageStore::get_client(Cassandane::IMAPMessageStore=HASH(0x42eaab0))
	called at Cassandane/Instance.pm line 835
	Cassandane::Instance::create_user(Cassandane::Instance=HASH(0x42f4098),
	"cassandane") called at Cassandane/Instance.pm line 886
	Cassandane::Instance::start(Cassandane::Instance=HASH(0x42f4098))
	called at Cassandane/Cyrus/TestCase.pm line 314
	Cassandane::Cyrus::TestCase::_start_instances(Cassandane::Cyrus::Caldav=HASH(0x4328f48))
	called at Cassandane/Cyrus/TestCase.pm line 305
	Cassandane::Cyrus::TestCase::set_up(Cassandane::Cyrus::Caldav=HASH(0x4328f48))
	called at Cassandane/Cyrus/Caldav.pm line 72
	Cassandane::Cyrus::Caldav::set_up(Cassandane::Cyrus::Caldav=HASH(0x4328f48))
	called at /usr/share/perl5/Test/Unit/TestCase.pm line 58
	Test::Unit::TestCase::run_bare(Cassandane::Cyrus::Caldav=HASH(0x4328f48))
	called at /usr/share/perl5/Test/Unit/Result.pm line 103
	Test::Unit::Result::__ANON__() called at
	/usr/share/perl5/Test/Unit/Result.pm line 119
	Test::Unit::Result::run_protected(Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Cyrus::Caldav=HASH(0x4328f48), CODE(0x43163c8))
	called at /usr/share/perl5/Test/Unit/Result.pm line 107
	Test::Unit::Result::run(Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Cyrus::Caldav=HASH(0x4328f48)) called at
	/usr/share/perl5/Test/Unit/TestCase.pm line 51
	Test::Unit::TestCase::run(Cassandane::Cyrus::Caldav=HASH(0x4328f48),
	Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Unit::RunnerPretty=HASH(0x3855f48)) called at
	/usr/share/perl5/Test/Unit/TestSuite.pm line 278
	Test::Unit::TestSuite::run(Test::Unit::TestSuite=HASH(0x2037140),
	Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Unit::RunnerPretty=HASH(0x3855f48)) called at
	Cassandane/Unit/TestPlan.pm line 737
	Cassandane::Unit::TestPlan::_run_workitem(Cassandane::Unit::TestPlan=HASH(0x3855d68),
	HASH(0x4315fd8), Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Unit::RunnerPretty=HASH(0x3855f48), 0) called at
	Cassandane/Unit/TestPlan.pm line 846
	Cassandane::Unit::TestPlan::__ANON__(HASH(0x4315fd8)) called at
	Cassandane/Unit/TestPlan.pm line 203
	Cassandane::Unit::Worker::_mainloop(Cassandane::Unit::Worker=HASH(0x431fd10))
	called at Cassandane/Unit/TestPlan.pm line 164
	Cassandane::Unit::Worker::start(Cassandane::Unit::Worker=HASH(0x431fd10))
	called at Cassandane/Unit/TestPlan.pm line 305
	Cassandane::Unit::WorkerPool::start(Cassandane::Unit::WorkerPool=HASH(0x431fc68))
	called at Cassandane/Unit/TestPlan.pm line 850
	Cassandane::Unit::TestPlan::run(Cassandane::Unit::TestPlan=HASH(0x3855d68),
	Test::Unit::Result=HASH(0x2036f30),
	Cassandane::Unit::RunnerPretty=HASH(0x3855f48)) called at
	/usr/share/perl5/Test/Unit/TestRunner.pm line 54
	Test::Unit::TestRunner::do_run(Cassandane::Unit::RunnerPretty=HASH(0x3855f48),
	Cassandane::Unit::TestPlan=HASH(0x3855d68), 0) called at
	./testrunner.pl line 123
	main::__ANON__(Cassandane::Unit::TestPlan=HASH(0x3855d68),
	GLOB(0x3855e70)) called at ./testrunner.pl line 260
=====> Daemon[385] checking for stray processes on ports: 9116 9117

Test was not successful.

More information about the Cyrus-devel mailing list