We are currently running inn2 2.6.3 on Debian buster. We are attempting to bring up a bullseye server to replace it. The bullseye server is running inn2 2.6.4. The new bullseye service is not allowing nn connections. When I connect to the new bullseye
server and attempt to post a new message I see this error (using Thunderbird NN client):
post failed 437 Xref: header required in xrefslave mode
On the buster server I see these inn2-related processes running:
news 1268 1 0 May09 ? 00:13:45 /usr/lib/news/bin/innd
news 1269 1 0 May09 ? 00:00:34 /bin/sh /usr/lib/news/bin/innwatch -i 60
news 14138 1268 0 00:08 ? 00:00:00 /usr/lib/news/bin/innfeed
news 14083 1268 0 00:08 ? 00:00:00 controlchan
On the new bullseye server I see only these inn2-related processes:
news 1045612 1 0 20:00 ? 00:00:00 /usr/lib/news/bin/innd -f news 1045634 1045612 0 20:00 ? 00:00:00 /bin/sh /usr/lib/news/bin/innwatch -i 60
news 1045635 1045612 0 20:00 ? 00:00:00 /usr/bin/perl -w /usr/lib/news/bin/cnfsstat -i 60 -s -l -P
news 1045655 1045612 0 20:00 ? 00:00:00 controlchan
So the innfeed process is not running on the new bullseye server. We are using identical inn2 configurations on both servers (except we only have a few groups on the new bullseye server so far). Looking at /var/run/news I see innfeed.pid for the buster
server but no such file for the bullseye server.
Any suggestions as to the next step in troubleshooting?
Looking at the logs on the bullseye server I see this (after a "service inn2 restart"):
May 22 19:51:07 usenet-dev.example.com systemd[1]: Stopping InterNetNews...
May 22 19:51:07 usenet-dev.example.com innd[1044925]: ctlinnd command q:rc.news stop
May 22 19:51:07 usenet-dev.example.com innd[1044925]: SERVER shutdown rc.news stop
May 22 19:51:07 usenet-dev.example.com innd[1044925]: controlchan! closed
May 22 19:51:07 usenet-dev.example.com innd[1044925]: control:12 closed
May 22 19:51:07 usenet-dev.example.com innd[1044925]: localconn:14 closed
May 22 19:51:07 usenet-dev.example.com innd[1044925]: remconn:15 closed
May 22 19:51:07 usenet-dev.example.com innd[1044925]: remconn:17 closed
May 22 19:51:07 usenet-dev.example.com innd[1044925]: SERVER perl filtering disabled
May 22 19:51:08 usenet-dev.example.com innd[1044925]: SERVER shutdown rc.news stop
May 22 19:51:12 usenet-dev.example.com rc.news[1045203]: Stopping innd: .
May 22 19:51:12 usenet-dev.example.com systemd[1]: inn2.service: Succeeded.
May 22 19:51:12 usenet-dev.example.com systemd[1]: Stopped InterNetNews.
May 22 19:51:12 usenet-dev.example.com systemd[1]: Starting InterNetNews...
May 22 19:51:12 usenet-dev.example.com rc.news[1045227]: Scheduled start of /usr/lib/news/bin/innwatch.
May 22 19:51:12 usenet-dev.example.com rc.news[1045227]: Scheduled start of cnfsstat.
May 22 19:51:12 usenet-dev.example.com rc.news[1045227]: Starting innd.
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER descriptors 1023 May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER outgoing 1010
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER ccsetup control:12 May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER lcsetup localconn:14
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER rcsetup remconn:15 May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER rcsetup remconn:17 May 22 19:51:12 usenet-dev.example.com innd[1045227]: controlchan! spawned controlchan!:21:proc:1045270
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER perl filtering enabled
May 22 19:51:12 usenet-dev.example.com innd[1045227]: pyfilter /etc/news/filter/filter_innd.py not installed
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER Python filtering disabled
May 22 19:51:12 usenet-dev.example.com systemd[1]: Started InterNetNews.
May 22 19:51:12 usenet-dev.example.com innd[1045227]: SERVER starting
May 22 19:51:12 usenet-dev.example.com controlchan[1045270]: starting
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class META for groups matching "*.binar*,alt.anonymous.messages" Buffer B01, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class META for groups matching "*.binar*,alt.anonymous.messages" Buffer B02, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B03, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B04, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B05, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B06, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B07, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:12 usenet-dev.example.com cnfsstat[1045250]: Class TEXT for groups matching "*" Buffer B08, len: 9765.62 Mbytes, used: 0.31 Mbytes ( 0.0%) 0 cycles
May 22 19:52:13 usenet-dev.example.com innd[1045227]: ctlinnd command s
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: nagios03.example.com (171.67.217.117) connect - port 119
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: Reading access from /etc/news/readers.conf
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: Auth strategy 'localhost' does not match client. Removing.
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: Auth strategy 'dev' does not match client. Removing.
... several more like this ...
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: nagios03.example.com res <monitoring>
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: nagios03.example.com no_match_user <monitoring>
[email protected]
... several more like this ...
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: nagios03.example.com times user 0.000 system 0.009 idle 0.000 elapsed 0.030
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: DIGEST-MD5 common mech free
May 22 19:53:52 usenet-dev.example.com nnrpd[1045452]: nagios03.example.com time 30 idle 4(1) nntpwrite 0(3)
--- SoupGate-Win32 v1.05
* Origin: fsxNet Usenet Gateway (21:1/5)