Daily Usenet report for paganini.linuxd.org

Jul 8 00:00:05 -- Jul 9 00:00:02

Unknown entries from news log file:

First 18 / 18 lines (100.0%)

Jul 8 03:03:24 paganini overchan[11276]: input line too long, skipping
Jul 8 03:07:36 paganini overchan[11276]: input line too long, skipping
Jul 8 09:51:53 paganini overchan[11276]: input line too long, skipping
Jul 8 13:00:28 paganini nnrpd[29665]: unable to get certificate from '/etc/news/cert.pem'
Jul 8 13:00:28 paganini nnrpd[29665]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jul 8 13:00:29 paganini nnrpd[29666]: unable to get certificate from '/etc/news/cert.pem'
Jul 8 13:00:29 paganini nnrpd[29666]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jul 8 13:00:30 paganini nnrpd[29667]: unable to get certificate from '/etc/news/cert.pem'
Jul 8 13:00:30 paganini nnrpd[29667]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jul 8 13:00:31 paganini nnrpd[29668]: unable to get certificate from '/etc/news/cert.pem'
Jul 8 13:00:31 paganini nnrpd[29668]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jul 8 13:00:32 paganini nnrpd[29669]: unable to get certificate from '/etc/news/cert.pem'
Jul 8 13:00:32 paganini nnrpd[29669]: error initializing TLS: [CA_file: ] [CA_path: /etc/news] [cert_file: /etc/news/cert.pem] [key_file: /etc/news/key.pem]
Jul 8 14:29:08 paganini overchan[11276]: input line too long, skipping
Jul 8 14:38:15 paganini overchan[11276]: input line too long, skipping
Jul 8 15:04:02 paganini overchan[11276]: input line too long, skipping
Jul 8 22:28:36 paganini overchan[11276]: input line too long, skipping
Jul 9 00:00:02 paganini overchan[32279]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)

Log entries by program:

Program nameLines%LinesSize%Size
innfeed 101288 64.5%12.5 MB 52.0%
inn 35331 22.5%8.7 MB 36.3%
nnrpd 10880 6.9%1.2 MB 4.9%
innd 9376 6.0%1.6 MB 6.7%
nocem 47 0.0%7.8 KB 0.0%
overchan 9 0.0%1.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 156932 100.0%24.0 MB100.0%

History cache:

ReasonCount%Count
Positive hits 111968 54.3%
Negative hits 59872 29.0%
Do not exist 34477 16.7%
Cache misses 66 0.0%
TOTAL: 4 206383 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.651 0.0%149 0.000 4.369 77.500
article cleanup00:00:00.242 0.0%34738 0.000 0.007 0.031
article logging00:00:00.336 0.0%35240 0.000 0.010 0.115
article parse00:00:00.720 0.0%52176 0.000 0.014 0.046
article write00:01:07.697 0.1%24327 0.134 2.783 28.250
artlog/artcncl00:00:00.000 0.0%149 0.000 0.000 0.000
data move00:00:00.118 0.0%221673 0.000 0.001 0.003
hisgrep/artcncl00:00:00.084 0.0%148 0.000 0.568 19.667
hishave/artcncl00:00:00.004 0.0%149 0.000 0.027 0.500
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.556 0.0%207831 0.014 0.046 0.369
history sync00:00:00.073 0.0%394 0.000 0.185 16.333
history write00:00:59.865 0.1%34738 0.051 1.723 5.565
hiswrite/artcncl00:00:00.000 0.0%1 0.000 0.000 0.000
idle23:56:31.561 99.7%217272 213.433 396.699 995.739
nntp read00:00:01.637 0.0%221136 0.000 0.007 0.014
overview write00:00:28.994 0.0%24327 0.021 1.192 5.368
perl filter00:01:03.587 0.1%34738 0.908 1.830 12.576
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.525 0.0%73234 0.000 0.021 0.046
TOTAL: 24:01:07.25424:00:26.650100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.102 0.0%39584 0.000 0.003 0.012
article prepare00:00:00.002 0.0%18184 0.000 0.000 0.014
article read00:00:03.682 0.0%3972 0.000 0.927 32.857
backlog stats00:00:00.179 0.0%339915 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.347 0.0%180377 0.002 0.007 0.018
data write00:00:02.295 0.0%156051 0.007 0.015 0.026
idle23:42:44.567 99.3%339915 165.465 251.135 448.756
status file00:10:01.003 0.7%42348 7.211 14.192 29.446
TOTAL: 23:53:11.02023:52:53.177100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle03:36:07.537 99.6%25358 0.000 511.379600094.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.028 0.0%51197 0.000 0.020 0.463
readart00:00:06.132 0.0%11753 0.161 0.522 3.598
TOTAL: 03:36:57.53003:36:14.697 99.7%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
TOTAL: 11 171

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 56133 20499 32811 2823 36%47:50:22
2newsfeed.neva.ru 146 82463 2907 72083 7473 3%72:47:16
3news.fcku.it 1 9536 821 8535 180 8%23:48:17
4localhost 25 0 0 0 0 0%00:00:00
5nntp.pasdenom.info 2 0 0 0 0 0%00:20:01
TOTAL: 5 178 148132 24227 113429 10476 16%144:45:56
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net74.5 MB0.0 KB15.7 MB90.2 MB 82%4.0 KB
2newsfeed.neva.ru7.9 MB0.0 KB24.8 MB32.7 MB 24%3.2 KB
3news.fcku.it2.1 MB0.0 KB1.1 MB3.2 MB 65%3.3 KB
4localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
5nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 584.5 MB0.0 KB41.7 MB126.2 MB 66%3.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jul 8 00:00:05 - 00:59:59 956 3.9% 0.273.1 MB 3.7% 0.90
Jul 8 01:00:00 - 01:59:59 726 3.0% 0.202.7 MB 3.2% 0.76
Jul 8 02:00:00 - 02:59:59 649 2.7% 0.182.8 MB 3.3% 0.80
Jul 8 03:00:00 - 03:59:59 478 2.0% 0.131.5 MB 1.7% 0.41
Jul 8 04:00:00 - 04:59:59 511 2.1% 0.141.6 MB 1.9% 0.45
Jul 8 05:00:00 - 05:59:59 495 2.0% 0.142.0 MB 2.4% 0.58
Jul 8 06:00:00 - 06:59:59 583 2.4% 0.161.8 MB 2.2% 0.53
Jul 8 07:00:00 - 07:59:59 510 2.1% 0.141.7 MB 2.0% 0.49
Jul 8 08:00:00 - 08:59:59 735 3.0% 0.202.1 MB 2.5% 0.61
Jul 8 09:00:00 - 09:59:59 1005 4.1% 0.282.7 MB 3.2% 0.77
Jul 8 10:00:00 - 10:59:59 1105 4.6% 0.314.8 MB 5.7% 1.36
Jul 8 11:00:00 - 11:59:59 1213 5.0% 0.343.8 MB 4.5% 1.09
Jul 8 12:00:00 - 12:59:59 1340 5.5% 0.375.7 MB 6.7% 1.62
Jul 8 13:00:00 - 13:59:59 1282 5.3% 0.364.5 MB 5.3% 1.28
Jul 8 14:00:00 - 14:59:59 1263 5.2% 0.353.7 MB 4.4% 1.06
Jul 8 15:00:00 - 15:59:59 1272 5.2% 0.353.3 MB 3.9% 0.93
Jul 8 16:00:00 - 16:59:59 1445 6.0% 0.4010.4 MB 12.3% 2.96
Jul 8 17:00:00 - 17:59:59 1453 6.0% 0.405.0 MB 6.0% 1.43
Jul 8 18:00:00 - 18:59:59 1358 5.6% 0.384.4 MB 5.2% 1.25
Jul 8 19:00:00 - 19:59:59 1367 5.6% 0.383.9 MB 4.6% 1.11
Jul 8 20:00:00 - 20:59:59 1217 5.0% 0.344.3 MB 5.1% 1.22
Jul 8 21:00:00 - 21:59:59 1156 4.8% 0.322.8 MB 3.3% 0.79
Jul 8 22:00:00 - 22:59:59 1124 4.6% 0.313.1 MB 3.7% 0.88
Jul 8 23:00:00 - 23:59:59 1030 4.2% 0.292.7 MB 3.2% 0.78
Jul 9 00:00:00 - 00:00:02 4 0.0% 2.0023.2 KB 0.0% 11.59
TOTAL: 23:59:57 24277 100.0% 0.2884.6 MB 100.0% 1.00
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7412 1314 0 0 0 0 0 0 6098
2linuxd-out.news.weretis.net 3287 1599 0 0 0 0 0 0 1688
3news.fcku.it 207 6 0 0 0 0 0 0 201
TOTAL: 3 10906 2919 0 0 0 0 0 0 7987

Unwanted newsgroups [Top 20]:

NewsgroupCount
fa.linux.kernel 868
mailing.freebsd.pkg-fallout 146
mailing.freebsd.ports-bugs 97
lada.talk 97
mailing.freebsd.svn-src-all 62
fa.freebsd.cvs-all 60
alt.sex.breasts 60
it.comp.os.win.windows10 54
lada.auto 48
mailing.freebsd.svn-ports-all 48
it-alt.sport.calcio.juventus 47
mailing.freebsd.svn-ports-head 42
mailing.freebsd.svn-src-head 37
mozilla.support.thunderbird 36
mailing.gcc.gcc-cvs 36
gmane.linux.alsa.devel 29
mailing.openbsd.misc 28
mozilla.dev.mdc 26
mailing.database.pgsql-general 26
mailing.openssl.cvs 25
TOTAL: 215 2919

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5348
EMP (phn path) 1204
EMP (phl) 600
EMP (phn nph) 140
PFM bot misc 109
Too many newsgroups 35
User-issued spam cancel 17
Binary Payload 16
User-issued cancel 8
Binary: non-binary distribution 5
HTML post 3
Too many newsgroups (meow) 2
Binary: misplaced rar 2
TOTAL: 13 7489

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 451
newsfeed.neva.ru 42
news.fcku.it 5
TOTAL: 3 498
TOTAL: 1 498

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 32014 1110 20567 1823 0 224 3%23:59:46
2nntp.pasdenom.info 43827 654 22982 479 0 904 1%23:57:42
3neva.ru 32502 138 21023 232 0 165 0%23:59:49
4weretis.net 4049 119 3523 0 0 8 2%23:59:07
5news.mixmin.net 0 0 0 0 0 0 0%00:10:00
6eternal-september 0 0 0 0 0 20277 0%23:59:49
7news.stack.nl 2859 0 0 0 0 26964 0%23:59:49
8news.albasani.net 2858 0 0 0 0 26140 0%23:59:49
9vc89.vc.panix.com 2838 0 0 0 0 27114 0%23:59:49
10etla.org 0 0 0 0 0 24277 0%23:59:49
11news.killfile.org 2850 0 0 0 0 27126 0%23:59:49
12news.netfront.net 2747 0 0 0 0 26954 0%23:59:48
TOTAL: 12 126544 2021 68095 2534 0 180153 1%264:05:06
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it3.5 MB8.1 MB11.6 MB0.1 KB/s4.1 KB23:59:46
2nntp.pasdenom.info2.0 MB1.2 MB3.2 MB0.0 KB/s2.9 KB23:57:42
3neva.ru657.8 KB1.4 MB2.1 MB0.0 KB/s5.7 KB23:59:49
4weretis.net282.8 KB0.0 KB282.8 KB0.0 KB/s2.4 KB23:59:07
5news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
6eternal-september0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
7news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
11news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:49
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:48
TOTAL: 126.4 MB10.7 MB17.2 MB0.0 KB/s3.9 KB264:05:06
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 120 80567.7 KB 63 0 000:43:07
TOTAL: 1 120 80567.7 KB 63 0 003:36:56

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 120 80567.7 KB 63 0 000:43:07
2*.188.099.pools.vodafone-ip.de 129 00.0 KB 0 0 000:00:27
3*.nielsenmedia.com 19 00.0 KB 0 0 002:23:02
4unresolved 9 00.0 KB 0 0 000:30:18
TOTAL: 4 277 80567.7 KB 63 0 003:36:56

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 1.224 0.060 0.00002:23:02
mtrlpq2314w-lp140-04-1177941735.dsl.bell.ca 10.952 1.732 0.00000:43:07
71.98.160.36 0.220 0.012 0.00000:30:14
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 6.792 0.416 0.00000:00:27
198.7.57.46 0.320 0.020 0.00000:00:03
TOTAL: 5 19.508 2.240 0.00003:36:56

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-099-106-132.188.099.pools.vodafone-ip.de 129
challenger.nielsenmedia.com 19
198.7.57.46 6
71.98.160.36 3
TOTAL: 4 157

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 4 1
71.98.160.36 1 0
TOTAL: 2 5 1

Newsgroup request counts (by category):

CategoryCountPct
1alt 42 52.5%
2rec 13 16.2%
3talk 11 13.8%
4uk 5 6.2%
5comp 2 2.5%
6misc 2 2.5%
7soc 2 2.5%
8dictator 1 1.2%
9sci 1 1.2%
10can 1 1.2%
TOTAL: 10 80100.0%

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

NewsgroupCount
1rec.arts.tv 9
2talk.politics.guns 9
3alt.comp.freeware 8
4alt.fan.rush-limbaugh 7
5alt.home.repair 6
6alt.happy.valley 4
7alt.christnet.christianlife 4
8alt.life.sucks 3
9uk.legal 3
10soc.culture.usa 2
11alt.sports.basketball.nba.gs-warriors 2
12talk.politics.misc 2
13rec.food.cooking 2
14alt.comp.os.windows-10 2
15alt.california 2
16misc.survivalism 2
17comp.lang.c 1
18alt.russian.z1 1
19can.politics 1
20uk.rec.cycling 1
21rec.sport.tennis 1
22uk.politics.misc 1
23dictator.europe 1
24sci.physics 1
25comp.lang.ada 1
26alt.conspiracy 1
27alt.suicide.methods 1
28rec.autos.sport.f1 1
29alt.os.linux.mint 1
TOTAL: 29 80