Daily Usenet report for news.niel.me

Jan 24 04:15:01 -- Jan 25 04:15:02

Unknown entries from news log file:

First 12 / 12 lines (100.0%)

Jan 24 14:03:23 rpi-studio innfeed[30124]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 24 14:04:29 rpi-studio innfeed[30124]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 24 14:04:29 rpi-studio innfeed[30124]: i2pn.org:0 connect: Network is unreachable
Jan 24 14:04:29 rpi-studio innfeed[30124]: news.nntp4.net:0 connect: Network is unreachable
Jan 24 14:04:29 rpi-studio innfeed[30124]: news.pasdenom.info:0 connect: Network is unreachable
Jan 24 14:04:29 rpi-studio innfeed[30124]: news.gegeweb.org:0 connect: Network is unreachable
Jan 24 14:06:30 rpi-studio innfeed[30124]: news.tnetconsulting.net:0 connect: Network is unreachable
Jan 24 14:09:24 rpi-studio innfeed[30124]: nntp.terraraq.uk:0 connect: Network is unreachable
Jan 24 14:10:31 rpi-studio innfeed[30124]: i2pn.org:0 connect: Network is unreachable
Jan 24 14:10:31 rpi-studio innfeed[30124]: news.nntp4.net:0 connect: Network is unreachable
Jan 24 14:10:31 rpi-studio innfeed[30124]: news.pasdenom.info:0 connect: Network is unreachable
Jan 24 14:10:31 rpi-studio innfeed[30124]: news.gegeweb.org:0 connect: Network is unreachable

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 26221 73.5%3.4 MB 69.9%
inn 4862 13.6%852.0 KB 17.3%
nnrpd 2467 6.9%274.6 KB 5.6%
innd 2102 5.9%353.2 KB 7.2%
controlchan 1 0.0%0.0 KB 0.0%
nocem 1 0.0%0.1 KB 0.0%
TOTAL: 6 35654 100.0%4.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 8286 41.5%
Negative hits 6934 34.8%
Do not exist 4711 23.6%
Cache misses 13 0.1%
TOTAL: 4 19944 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
artcncl/perl00:00:00.021 0.0%16 0.000 1.312 6.000
article cancel00:00:00.005 0.0%19 0.000 0.263 1.000
article cleanup00:00:00.077 0.0%4795 0.000 0.016 0.143
article logging00:00:00.313 0.0%4819 0.000 0.065 0.250
article parse00:00:01.357 0.0%19285 0.000 0.070 0.267
article write00:00:02.837 0.0%4516 0.125 0.628 1.684
artlog/artcncl00:00:00.002 0.0%19 0.000 0.105 1.000
artlog/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
data move00:00:00.104 0.0%34366 0.000 0.003 0.032
hisgrep/artcncl00:00:00.000 0.0%17 0.000 0.000 0.000
hisgrep/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
hisgrep/perl00:00:00.001 0.0%26 0.000 0.038 0.500
hishave/artcncl00:00:00.000 0.0%19 0.000 0.000 0.000
hishave/artcncl/perl00:00:00.000 0.0%16 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:00.310 0.0%20001 0.000 0.015 0.085
history sync00:00:00.037 0.0%404 0.000 0.092 1.333
history write00:00:00.970 0.0%4720 0.000 0.206 1.514
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle24:00:31.994 99.9%34621 417.726 2496.519300312.000
nntp read00:00:01.329 0.0%33738 0.000 0.039 0.108
overview write00:00:02.654 0.0%4516 0.083 0.588 5.619
perl filter00:00:23.448 0.0%4720 1.626 4.968 42.806
python filter00:00:00.007 0.0%4723 0.000 0.001 0.050
site send00:00:01.138 0.0%9096 0.000 0.125 0.450
TOTAL: 24:01:17.17624:01:06.604100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.141 0.0%19170 0.000 0.007 0.111
article prepare00:00:00.001 0.0%1192 0.000 0.001 0.017
article read00:00:00.039 0.0%288 0.000 0.135 2.667
backlog stats00:00:00.132 0.0%57991 0.000 0.002 0.020
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:00.837 0.0%29656 0.000 0.028 0.222
data write00:00:01.542 0.0%25080 0.000 0.061 0.167
idle23:59:08.054 99.9%57991 206.689 1488.991 13651.818
status file00:00:40.590 0.0%10822 1.000 3.751 20.175
TOTAL: 23:59:58.64423:59:51.336100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
hisgrep00:00:00.002 0.0%7 0.000 0.286 1.000
idle00:01:30.623 7.5%1544 6.500 58.694 6765.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.634 0.1%5679 0.000 0.112 0.862
readart00:00:00.549 0.0%230 0.000 2.387 3.488
TOTAL: 00:20:09.18800:01:31.808 7.6%----

Control commands to INND:

CommandNumber
flush 5
flushlogs 2
go 2
logmode 2
lowmark 1
mode 149
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 171

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1news-out.aioe.org 3 4327 3082 1058 187 71%24:28:25
2feed-out.gegeweb.org 122 3210 1052 2112 46 32%23:03:04
3i2pn.org 1 1384 398 965 21 28%12:14:02
4usenet.pasdenom.info 1 3461 224 3236 1 6%29:54:47
5nntp.terraraq.uk 1 1626 76 1534 16 4%14:42:35
6gegeweb-out.news.tnetconsulting.net 2 4320 39 4277 4 0%27:54:33
7news.nntp4.net 1 1512 17 1471 24 1%12:00:54
8localhost 28 3 3 0 0 100%00:00:00
TOTAL: 8 159 19843 4891 14653 299 24%144:18:20
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feed-out.gegeweb.org20.9 MB162.6 KB162.6 KB21.2 MB 98%19.8 KB
2news-out.aioe.org10.2 MB65.7 KB636.6 KB10.9 MB 93%3.4 KB
3i2pn.org1.4 MB0.0 KB119.5 KB1.5 MB 92%3.7 KB
4gegeweb-out.news.tnetconsulting.net826.8 KB0.0 KB30.9 KB857.7 KB 96%19.9 KB
5news.nntp4.net712.5 KB0.0 KB62.7 KB775.1 KB 91%18.9 KB
6nntp.terraraq.uk661.9 KB0.0 KB50.6 KB712.5 KB 92%7.7 KB
7usenet.pasdenom.info482.7 KB0.0 KB1.3 KB483.9 KB 99%2.2 KB
8localhost4.4 KB0.0 KB0.0 KB4.4 KB100%1.5 KB
TOTAL: 835.1 MB228.3 KB1.0 MB36.4 MB 96%7.2 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jan 24 04:15:01 - 04:59:59 84 1.9% 0.03335.9 KB 0.0% 0.12
Jan 24 05:00:00 - 05:59:59 102 2.3% 0.03294.0 KB 0.0% 0.08
Jan 24 06:00:00 - 06:59:59 125 2.8% 0.03505.1 KB 2.0% 0.14
Jan 24 07:00:00 - 07:59:59 80 1.8% 0.02413.8 KB 1.7% 0.11
Jan 24 08:00:00 - 08:59:59 95 2.1% 0.03266.9 KB 1.1% 0.07
Jan 24 09:00:00 - 09:59:59 127 2.8% 0.04505.3 KB 2.0% 0.14
Jan 24 10:00:00 - 10:59:59 130 2.9% 0.04397.7 KB 1.6% 0.11
Jan 24 11:00:00 - 11:59:59 211 4.7% 0.06637.2 KB 2.6% 0.18
Jan 24 12:00:00 - 12:59:59 164 3.6% 0.05885.7 KB 3.6% 0.25
Jan 24 13:00:00 - 13:59:59 137 3.0% 0.04478.0 KB 1.9% 0.13
Jan 24 14:00:00 - 14:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jan 24 15:00:00 - 15:59:59 0 0.0% 0.000.0 KB 0.0% 0.00
Jan 24 16:00:00 - 16:59:59 177 3.9% 0.05610.4 KB 2.5% 0.17
Jan 24 17:00:00 - 17:59:59 611 13.5% 0.172.0 MB 8.4% 0.58
Jan 24 18:00:00 - 18:59:59 309 6.8% 0.091.9 MB 7.8% 0.54
Jan 24 19:00:00 - 19:59:59 275 6.1% 0.081.8 MB 7.4% 0.51
Jan 24 20:00:00 - 20:59:59 303 6.7% 0.084.2 MB 17.5% 1.21
Jan 24 21:00:00 - 21:59:59 285 6.3% 0.08973.8 KB 3.9% 0.27
Jan 24 22:00:00 - 22:59:59 299 6.6% 0.083.5 MB 14.6% 1.01
Jan 24 23:00:00 - 23:59:59 320 7.1% 0.094.3 MB 17.9% 1.23
Jan 25 00:00:00 - 00:59:59 196 4.3% 0.053.6 MB 15.0% 1.04
Jan 25 01:00:00 - 01:59:59 187 4.1% 0.051.7 MB 7.1% 0.49
Jan 25 02:00:00 - 02:59:59 125 2.8% 0.032.9 MB 12.0% 0.83
Jan 25 03:00:00 - 03:59:59 147 3.2% 0.042.2 MB 8.9% 0.62
Jan 25 04:00:00 - 04:15:02 36 0.8% 0.04311.9 KB 1.3% 0.35
TOTAL: 24:00:01 4525 100.0% 0.0524.2 MB 100.0% 0.29
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1news-out.aioe.org 199 4 0 29 0 0 0 0 166
2feed-out.gegeweb.org 51 0 0 46 0 0 0 0 5
3news.nntp4.net 24 0 0 0 0 0 0 0 24
4i2pn.org 15 0 0 0 0 0 0 0 15
5nntp.terraraq.uk 12 0 0 0 0 0 0 0 12
6gegeweb-out.news.tnetconsulting.net 1 0 0 0 0 0 0 0 1
TOTAL: 6 302 4 0 75 0 0 0 0 223

Unwanted newsgroups [Top 20]:

NewsgroupCount
fr.petites-annonces.divers 3
news.test 1
TOTAL: 2 4

INND Perl filter [Top 20]:

ReasonCount
Bad path (news.giganews.com.POSTED) 109
Too many newsgroups 40
EMP (phn nph) 33
EMP (md5) 8
Cancel of <mn.c2ba7e61619a6c08.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c2b77e61353a76b4.145333@frano.livoie.com> without Cancel-Key 1
Cancels abusifs 1
Cancel of <mn.c2bc7e61bf2d5fc3.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c4247e61775f85a3.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c4287e61ebe0747e.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c2c97e61d5ca14fa.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c2b97e6117337f50.145333@frano.livoie.com> without Cancel-Key 1
Cancel of <mn.c4267e61da5566e3.145333@frano.livoie.com> without Cancel-Key 1
TOTAL: 13 199

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
news-out.aioe.org 17
feed-out.gegeweb.org 5
nntp.terraraq.uk 1
i2pn.org 1
TOTAL: 4 24
TOTAL: 1 24

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1i2pn.org 4452 70 3562 4 0 2704 1%23:59:57
2news.gegeweb.org 3816 35 3221 176 0 916 0%22:05:00
3news.tnetconsulting.net 2037 6 1244 5 0 3172 0%23:59:57
4news.nntp4.net 5781 1 4394 0 0 4191 0%22:04:37
5news.pasdenom.info 5249 0 4006 1 0 3991 0%22:04:36
6nntp.terraraq.uk 2838 0 2813 0 0 2081 0%23:59:57
7news.aioe.org 1292 0 503 0 0 1342 0%23:08:04
TOTAL: 7 25465 112 19743 186 0 18397 0%161:22:08
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1i2pn.org158.5 KB12.3 KB170.8 KB0.0 KB/s2.3 KB23:59:57
2news.gegeweb.org87.0 KB614.1 KB701.1 KB0.0 KB/s3.3 KB22:05:00
3news.tnetconsulting.net21.6 KB43.4 KB65.0 KB0.0 KB/s5.9 KB23:59:57
4news.nntp4.net1.4 KB0.0 KB1.4 KB0.0 KB/s1.4 KB22:04:37
5news.pasdenom.info0.0 KB1.4 KB1.4 KB0.0 KB/s1.4 KB22:04:36
6nntp.terraraq.uk0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:57
7news.aioe.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:08:04
TOTAL: 7268.5 KB671.1 KB939.6 KB0.0 KB/s3.2 KB161:22:08
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
176.72.173.4 12 1672.2 MB 9 0 000:00:51
2kbox.niel.me 5 2677.4 KB 10 1 000:10:29
32a0e:e701:1154:0:2050:8a9f:23e5:2b2c 3 1623.2 KB 4 1 000:05:55
42a0e:e701:1154:0:50a3:e1a8:bf33:4b1 1 47.7 KB 2 0 000:01:02
52a0e:e701:1154:0:c58:26d0:f5d6:8a90 1 11.1 KB 1 1 000:00:41
TOTAL: 5 22 2142.3 MB 26 3 000:20:21

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 29 1672.2 MB 9 0 000:00:57
2*.niel.me 5 2677.4 KB 10 1 000:10:29
3? 5 2132.1 KB 7 2 000:07:39
4*.gegeweb.eu 262 00.0 KB 0 0 000:01:14
TOTAL: 4 301 2142.3 MB 26 3 000:20:21

NNRP auth users [Top 20]:

UserConn
mailman 260
gerald 10
TOTAL: 2 270

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
kbox.niel.me 0.491 0.184 0.03900:10:29
2a0e:e701:1154:0:2050:8a9f:23e5:2b2c 0.320 0.100 0.03400:05:55
2a0e:e701:1154:0:50a3:e1a8:bf33:4b1 0.102 0.010 0.01500:01:02
76.72.173.4 0.463 0.498 0.00000:00:51
sys.gegeweb.eu 3.251 3.132 1.34600:00:41
2a0e:e701:1154:0:c58:26d0:f5d6:8a90 0.137 0.088 0.01500:00:41
mail.gegeweb.eu 3.816 3.833 1.28800:00:33
88.165.5.23 0.484 0.453 0.00000:00:05
TOTAL: 8 9.064 8.298 2.73700:20:21

Curious NNRP server explorers [Top 20]:

SystemConn
mail.gegeweb.eu 143
sys.gegeweb.eu 119
88.165.5.23 17
TOTAL: 3 279

Newsgroup request counts (by category):

CategoryCountPct
1grisbi 170 79.4%
2fr 35 16.4%
3comp 5 2.3%
4local 3 1.4%
5news 1 0.5%
TOTAL: 5 214100.0%

Newsgroup request counts (by newsgroup) [Top 100]:

NewsgroupCount
1grisbi.test 85
2grisbi.bugsreports 82
3fr.usenet.distribution 25
4fr.usenet.forums.evolution 9
5comp.sys.raspberry-pi 3
6grisbi.devel 3
7local.test 3
8comp.infosystems.gemini 2
9news.admin.peering 1
10fr.usenet.documents 1
TOTAL: 10 214