Daily Usenet report for paganini.linuxd.org

Feb 23 00:00:06 -- Feb 24 00:00:03

Unknown entries from news log file:

First 4 / 4 lines (100.0%)

Feb 23 11:32:19 paganini overchan[11465]: input line too long, skipping
Feb 23 13:45:21 paganini overchan[11465]: input line too long, skipping
Feb 23 18:47:52 paganini overchan[11465]: input line too long, skipping
Feb 24 00:00:03 paganini overchan[22850]: 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 115610 66.3%14.4 MB 56.5%
inn 39959 22.9%8.6 MB 33.7%
innd 17985 10.3%2.4 MB 9.4%
nnrpd 563 0.3%78.3 KB 0.3%
nocem 126 0.1%21.0 KB 0.1%
overchan 5 0.0%0.8 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 174249 100.0%25.5 MB100.0%

History cache:

ReasonCount%Count
Positive hits 126500 52.4%
Negative hits 76098 31.5%
Do not exist 38966 16.1%
Cache misses 71 0.0%
TOTAL: 4 241635 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.739 0.0%201 0.000 3.677 31.000
article cleanup00:00:00.241 0.0%39114 0.000 0.006 0.023
article logging00:00:00.374 0.0%39693 0.000 0.009 0.027
article parse00:00:01.101 0.0%90570 0.000 0.012 0.032
article write00:00:51.786 0.1%22182 0.127 2.335 24.746
artlog/artcncl00:00:00.003 0.0%200 0.000 0.015 0.143
data move00:00:00.228 0.0%263393 0.000 0.001 0.006
hisgrep/artcncl00:00:00.027 0.0%199 0.000 0.136 7.333
hishave/artcncl00:00:00.002 0.0%201 0.000 0.010 1.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.112 0.0%242589 0.013 0.054 0.487
history sync00:00:00.015 0.0%393 0.000 0.038 0.667
history write00:00:41.858 0.0%39113 0.032 1.070 3.856
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:56:54.870 99.7%259347 184.739 332.431 998.826
nntp read00:00:01.723 0.0%268114 0.001 0.006 0.013
overview write00:00:23.052 0.0%22182 0.026 1.039 7.284
perl filter00:01:24.195 0.1%39113 1.211 2.153 5.780
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.157 0.0%66804 0.000 0.017 0.043
TOTAL: 24:01:03.42724:00:34.483100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.120 0.0%59806 0.000 0.002 0.012
article prepare00:00:00.002 0.0%23688 0.000 0.000 0.006
article read00:00:03.871 0.0%5430 0.000 0.713 23.812
backlog stats00:00:00.223 0.0%330850 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.369 0.0%177052 0.002 0.008 0.018
data write00:00:02.571 0.0%155170 0.006 0.017 0.032
idle23:41:28.942 99.2%330850 26.063 257.787 420.636
status file00:11:39.286 0.8%48422 10.364 14.441 27.450
TOTAL: 23:53:27.74323:53:16.384100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:22:48.173 99.9%484 29.333 40016.886602303.667
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.008 0.0%1276 0.000 0.006 0.500
readart00:00:00.109 0.0%19 0.000 5.737 24.000
TOTAL: 05:23:08.58605:22:48.290 99.9%----

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 7 52324 19193 29713 3418 36%49:00:47
2newsfeed.neva.ru 142 83109 2739 67115 13255 3%71:43:54
3news.fcku.it 2 11519 377 10933 209 3%29:49:11
4nntp.pasdenom.info 4 24837 73 24756 8 0%26:24:58
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 180 171789 22382 132517 16890 13%176:58:50
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net61.2 MB0.0 KB24.7 MB85.9 MB 71%3.9 KB
2newsfeed.neva.ru7.7 MB7.2 KB123.2 MB130.9 MB 5%8.4 KB
3news.fcku.it911.6 KB0.0 KB995.4 KB1.9 MB 47%3.3 KB
4nntp.pasdenom.info157.0 KB0.0 KB19.1 KB176.2 KB 89%2.2 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 569.9 MB7.2 KB149.0 MB218.9 MB 31%5.7 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 23 00:00:06 - 00:59:59 769 3.5% 0.212.6 MB 3.7% 0.74
Feb 23 01:00:00 - 01:59:59 705 3.2% 0.202.5 MB 3.6% 0.71
Feb 23 02:00:00 - 02:59:59 587 2.6% 0.162.1 MB 3.0% 0.59
Feb 23 03:00:00 - 03:59:59 472 2.1% 0.131.8 MB 2.6% 0.52
Feb 23 04:00:00 - 04:59:59 382 1.7% 0.111.2 MB 1.8% 0.35
Feb 23 05:00:00 - 05:59:59 471 2.1% 0.131.5 MB 2.2% 0.44
Feb 23 06:00:00 - 06:59:59 655 2.9% 0.182.5 MB 3.5% 0.70
Feb 23 07:00:00 - 07:59:59 521 2.3% 0.141.6 MB 2.3% 0.46
Feb 23 08:00:00 - 08:59:59 647 2.9% 0.181.9 MB 2.7% 0.54
Feb 23 09:00:00 - 09:59:59 949 4.3% 0.262.5 MB 3.6% 0.71
Feb 23 10:00:00 - 10:59:59 1042 4.7% 0.292.8 MB 4.0% 0.78
Feb 23 11:00:00 - 11:59:59 986 4.4% 0.272.7 MB 3.9% 0.78
Feb 23 12:00:00 - 12:59:59 1051 4.7% 0.292.8 MB 4.0% 0.78
Feb 23 13:00:00 - 13:59:59 1100 5.0% 0.314.2 MB 6.0% 1.19
Feb 23 14:00:00 - 14:59:59 1138 5.1% 0.325.1 MB 7.3% 1.44
Feb 23 15:00:00 - 15:59:59 1110 5.0% 0.312.9 MB 4.2% 0.82
Feb 23 16:00:00 - 16:59:59 1060 4.8% 0.293.4 MB 4.8% 0.96
Feb 23 17:00:00 - 17:59:59 1328 6.0% 0.374.5 MB 6.5% 1.29
Feb 23 18:00:00 - 18:59:59 1403 6.3% 0.394.2 MB 6.1% 1.21
Feb 23 19:00:00 - 19:59:59 1264 5.7% 0.353.9 MB 5.6% 1.12
Feb 23 20:00:00 - 20:59:59 1145 5.2% 0.323.1 MB 4.4% 0.88
Feb 23 21:00:00 - 21:59:59 1153 5.2% 0.323.2 MB 4.6% 0.92
Feb 23 22:00:00 - 22:59:59 1144 5.1% 0.323.1 MB 4.5% 0.89
Feb 23 23:00:00 - 23:59:59 1109 5.0% 0.313.3 MB 4.8% 0.94
Feb 24 00:00:00 - 00:00:03 30 0.1% 10.00118.2 KB 0.2% 39.40
TOTAL: 23:59:57 22221 100.0% 0.2669.6 MB 100.0% 0.83
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 13352 365 0 1 0 0 0 012986
2linuxd-out.news.weretis.net 3965 620 0 0 0 0 0 0 3345
3news.fcku.it 196 3 0 0 0 0 0 0 193
4nntp.pasdenom.info 10 5 0 0 0 0 0 0 5
TOTAL: 4 17523 993 0 1 0 0 0 016529

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 166
it-alt.sport.calcio.juventus 94
lada.talk 65
eagle.userchat.eng 56
it.comp.os.win.windows10 34
mozilla.dev.security.policy 27
lada.auto 26
fido.enet.sysop 26
mozilla.support.seamonkey 26
mpc.lists.freebsd.ports.bugs 25
mozilla.dev.platform 24
alt.windows7.general 17
mozilla.support.thunderbird 16
mpc.lists.nanog 14
gmane.comp.gis.openstreetmap 13
mozilla.tools.taskcluster 12
alt.survival 12
alt.politics.scorched-earth 12
gmane.comp.gis.openstreetmap.tagging 11
gmane.linux.alsa.devel 10
TOTAL: 133 993

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 13127
EMP (phn path) 2100
EMP (phl) 400
EMP (phn nph) 133
EMP (phr path) 103
Too many newsgroups 41
Binary Payload 16
User-issued cancel 14
Binary Image: misplaced jpg 8
Binary: non-binary distribution 4
HTML post 1
TOTAL: 11 15947

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 538
newsfeed.neva.ru 42
news.fcku.it 2
TOTAL: 3 582
TOTAL: 1 582

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1news.fcku.it 29439 2077 16916 3021 0 77 7%24:05:10
2nntp.pasdenom.info 29390 439 24305 91 0 20858 1%23:57:46
3weretis.net 3384 125 2929 0 0 24 3%23:58:38
4neva.ru 28236 93 19245 98 0 22 0%23:59:52
5eternal-september 24993 26 18858 0 0 41 0%23:59:53
6etla.org 0 0 0 0 0 22221 0%23:59:52
7news.killfile.org 2848 0 0 0 0 25068 0%23:59:53
8vc89.vc.panix.com 2840 0 0 0 0 25060 0%23:59:53
9news.stack.nl 2857 0 0 0 0 25041 0%23:59:53
10news.mixmin.net 0 0 0 0 0 0 0%00:10:00
11news.albasani.net 2866 0 0 0 0 24071 0%23:59:53
12news.netfront.net 2732 0 0 0 0 24872 0%23:59:52
TOTAL: 12 129585 2760 82253 3210 0 167355 2%264:10:35
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.fcku.it6.0 MB9.0 MB15.0 MB0.2 KB/s3.0 KB24:05:10
2nntp.pasdenom.info1.5 MB241.5 KB1.7 MB0.0 KB/s3.4 KB23:57:46
3neva.ru208.9 KB426.0 KB634.9 KB0.0 KB/s3.3 KB23:59:52
4weretis.net158.3 KB0.0 KB158.3 KB0.0 KB/s1.3 KB23:58:38
5eternal-september78.7 KB0.0 KB78.7 KB0.0 KB/s3.0 KB23:59:53
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
7news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
8vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
9news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
10news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
11news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
12news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
TOTAL: 127.9 MB9.7 MB17.6 MB0.0 KB/s3.0 KB264:10:35
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1challenger.nielsenmedia.com 15 1127.2 KB 5 0 002:33:03
247-192-187-101.drr02.clwr.fl.frontiernet.net 20 819.7 KB 3 0 002:49:37
TOTAL: 2 35 1947.0 KB 8 0 005:23:08

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.nielsenmedia.com 15 1127.2 KB 5 0 002:33:03
2*.drr02.clwr.fl.frontiernet.net 20 819.7 KB 3 0 002:49:37
3*.188.110.pools.vodafone-ip.de 126 00.0 KB 0 0 000:00:20
4unresolved 1 00.0 KB 0 0 000:00:06
TOTAL: 4 162 1947.0 KB 8 0 005:23:08

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 1.232 0.088 0.00002:49:37
challenger.nielsenmedia.com 1.076 0.076 0.00002:33:03
dslb-188-110-184-251.188.110.pools.vodafone-ip.de 6.592 0.396 0.00000:00:20
106.75.81.67 0.056 0.000 0.00000:00:06
? 0.052 0.004 0.00000:00:00
TOTAL: 5 9.008 0.564 0.00005:23:08

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-184-251.188.110.pools.vodafone-ip.de 126
106.75.81.67 1
TOTAL: 2 127

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP client timeouts [Top 20]:

SystemConnPeer
47-192-187-101.drr02.clwr.fl.frontiernet.net 5 2
challenger.nielsenmedia.com 5 1
TOTAL: 2 10 3

Newsgroup request counts (by category):

CategoryCountPct
1comp 19100.0%
TOTAL: 1 19100.0%

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

NewsgroupCount
1comp.lang.awk 19
TOTAL: 1 19