[Opendnssec-user] zone sign after upgrading to 1.3.1

[Göran Bengtson]

I tried an upgrade from 1.3.0 to 1.3.1. on a RedHat Enterprise 5.7 system
today and now zone transfer or explicit sign commands fail to trigger
a zone sign.

After increasing the verbosity the logs say:
Sep 12 11:48:51 ns-test ods-signerd: [fifo] unable to push item: max cap reached

I tried increasing FIFOQ_MAX_COUNT in signer/src/scheduler/fifoq.h from 1000
to 10000 and that got rid of that message, but the zones are still not signed
(at least not all the times they should be).  Later, when working on 10-15
zones the max cap reached message turned up again and  I also find
"ods-signerd: [fifo] popped item, count=9999" messages supporting the
conclusion that the fifo is full.  Is 1000 or 10000 really too small, or
is there another problem behind this?

More logs below. A guess is some race conditions (maybe between threads).
What does "no valid signconf.xml for zone chalmers.se yet" indicate?
The file is present and as far as I can se valid.

Anyone seen this before?

Sep 12 12:15:23 ns-test ods-signerd: [cmdhandler] done handling command verbosity 7[11]
Sep 12 12:16:54 ns-test ods-signerd: zone fetcher received NOTIFY for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: zone fetcher transferred zone chalmers.se serial 2011091207 successfully
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] 1 clients in progress...
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] accept client 15
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] received command sign chalmers.se[16]
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] sign zone command
Sep 12 12:16:54 ns-test ods-signerd: [scheduler] unschedule task [sign] for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] reschedule task for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [scheduler] schedule task [read] for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] zone chalmers.se scheduled for immediate re-sign
Sep 12 12:16:54 ns-test ods-signerd: [engine] wake up workers
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [scheduler] pop task for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [scheduler] unschedule task [read] for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] start working on zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] perform task [read] for zone chalmers.se at 1315822614
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] read zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] no valid signconf.xml for zone chalmers.se yet
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] continue task [sign] for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] finished working on zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [scheduler] schedule task [sign] for zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [task] On Mon Sep 12 13:16:54 2011 I will [sign] zone chalmers.se
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[1]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[2]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[3]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[2]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[3]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[4]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[2]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[4]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[5]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[3]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[5]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[6]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[4]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[6]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[7]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[5]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[7]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [worker[8]] wake up
Sep 12 12:16:54 ns-test ods-signerd: [worker[6]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[8]] report for duty
Sep 12 12:16:54 ns-test ods-signerd: [cmdhandler] done handling command sign chalmers.se[16]
Sep 12 12:16:54 ns-test ods-signerd: [worker[7]] nothing to do
Sep 12 12:16:54 ns-test ods-signerd: [worker[8]] nothing to do
Sep 12 12:17:35 ns-test ods-signerd: [cmdhandler] 1 clients in progress...
Sep 12 12:17:35 ns-test ods-signerd: [cmdhandler] accept client 15
Sep 12 12:17:35 ns-test ods-signerd: [cmdhandler] received command verbosity 0[11]
Sep 12 12:17:35 ns-test ods-signerd: [cmdhandler] verbosity command
Sep 12 12:17:35 ns-test ods-signerd: [log] switching log to syslog verbosity 0 (log level 2)