Daily Usenet report for paganini.linuxd.org

May 4 00:00:01 -- May 5 00:00:02

Unknown entries from news log file:

First 5 / 5 lines (100.0%)

May 4 05:25:43 paganini overchan[17525]: input line too long, skipping
May 4 09:50:02 paganini controlchan[17523]: Unknown "tags" control by robomod@news.nic.it
May 4 16:17:09 paganini overchan[17525]: input line too long, skipping
May 4 16:27:23 paganini overchan[17525]: input line too long, skipping
May 5 00:00:02 paganini overchan[30133]: 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 101456 44.9%12.5 MB 39.8%
nnrpd 74754 33.1%8.6 MB 27.4%
inn 35539 15.7%8.1 MB 25.9%
innd 14199 6.3%2.1 MB 6.8%
nocem 69 0.0%11.5 KB 0.0%
overchan 5 0.0%0.9 KB 0.0%
controlchan 2 0.0%0.2 KB 0.0%
TOTAL: 7 226024 100.0%31.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 116629 53.9%
Negative hits 65085 30.1%
Do not exist 34609 16.0%
Cache misses 43 0.0%
TOTAL: 4 216366 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.498 0.0%162 0.000 3.074 26.000
article cleanup00:00:00.245 0.0%34867 0.000 0.007 0.024
article logging00:00:00.575 0.0%35391 0.000 0.016 0.767
article parse00:00:00.775 0.0%61515 0.000 0.013 0.043
article write00:00:50.906 0.1%21707 0.080 2.345 20.860
artlog/artcncl00:00:00.000 0.0%162 0.000 0.000 0.000
data move00:00:00.152 0.0%229235 0.000 0.001 0.004
hisgrep/artcncl00:00:00.029 0.0%160 0.000 0.181 1.556
hishave/artcncl00:00:00.000 0.0%162 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:13.960 0.0%217881 0.017 0.064 0.909
history sync00:00:00.290 0.0%400 0.000 0.725 50.000
history write00:00:38.516 0.0%34866 0.040 1.105 5.490
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:57:08.794 99.7%227479 210.290 379.063 964.591
nntp read00:00:01.760 0.0%233962 0.001 0.008 0.033
overview write00:00:20.776 0.0%21707 0.012 0.957 5.823
perl filter00:01:10.057 0.1%34866 1.114 2.009 6.579
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.467 0.0%65263 0.004 0.022 0.065
TOTAL: 24:01:08.47124:00:28.800100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.087 0.0%36237 0.000 0.002 0.012
article prepare00:00:00.007 0.0%31948 0.000 0.000 0.006
article read00:00:03.838 0.0%6499 0.000 0.591 15.907
backlog stats00:00:00.258 0.0%344655 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.513 0.0%187527 0.001 0.008 0.016
data write00:00:02.959 0.0%165907 0.010 0.018 0.034
idle23:42:46.518 99.3%344655 155.073 247.687 463.086
status file00:09:55.187 0.7%42408 8.170 14.035 50.023
TOTAL: 23:53:04.67123:52:50.367100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle05:16:10.327 99.9%75441 37.302 251.459600443.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.585 0.0%152818 0.000 0.010 0.100
readart00:00:00.000 0.0%2 0.000 0.000 0.000
TOTAL: 05:16:34.67305:16:11.912 99.9%----

Control commands to INND:

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

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 46088 18741 25741 1606 40%48:12:40
2newsfeed.neva.ru 153 76332 2569 62345 11418 3%71:46:21
3news.fcku.it 1 7884 363 7368 153 4%22:57:00
4nntp.pasdenom.info 3 15599 25 15570 4 0%17:26:27
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 186 145903 21698 111024 13181 14%160:22:28
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net57.3 MB0.0 KB7.7 MB65.0 MB 88%3.3 KB
2newsfeed.neva.ru6.6 MB0.0 KB72.5 MB79.1 MB 8%5.8 KB
3news.fcku.it1.4 MB0.0 KB836.5 KB2.2 MB 62%4.4 KB
4nntp.pasdenom.info49.7 KB0.0 KB118.3 KB168.0 KB 29%5.8 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 565.4 MB0.0 KB81.2 MB146.5 MB 44%4.3 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
May 4 00:00:01 - 00:59:59 809 3.7% 0.223.0 MB 4.6% 0.85
May 4 01:00:00 - 01:59:59 569 2.6% 0.161.7 MB 2.6% 0.49
May 4 02:00:00 - 02:59:59 540 2.5% 0.151.5 MB 2.3% 0.42
May 4 03:00:00 - 03:59:59 436 2.0% 0.121.4 MB 2.1% 0.38
May 4 04:00:00 - 04:59:59 360 1.7% 0.101.2 MB 1.9% 0.35
May 4 05:00:00 - 05:59:59 397 1.8% 0.111.3 MB 2.0% 0.37
May 4 06:00:00 - 06:59:59 448 2.1% 0.121.5 MB 2.2% 0.42
May 4 07:00:00 - 07:59:59 540 2.5% 0.151.9 MB 2.9% 0.54
May 4 08:00:00 - 08:59:59 643 3.0% 0.182.3 MB 3.5% 0.65
May 4 09:00:00 - 09:59:59 887 4.1% 0.252.5 MB 3.8% 0.70
May 4 10:00:00 - 10:59:59 916 4.2% 0.252.5 MB 3.8% 0.71
May 4 11:00:00 - 11:59:59 1069 4.9% 0.303.2 MB 5.0% 0.92
May 4 12:00:00 - 12:59:59 984 4.5% 0.272.5 MB 3.8% 0.72
May 4 13:00:00 - 13:59:59 996 4.6% 0.282.4 MB 3.7% 0.70
May 4 14:00:00 - 14:59:59 1058 4.9% 0.293.7 MB 5.7% 1.05
May 4 15:00:00 - 15:59:59 1173 5.4% 0.333.7 MB 5.7% 1.06
May 4 16:00:00 - 16:59:59 1303 6.0% 0.364.0 MB 6.0% 1.12
May 4 17:00:00 - 17:59:59 1424 6.6% 0.403.9 MB 6.0% 1.12
May 4 18:00:00 - 18:59:59 1328 6.1% 0.373.7 MB 5.7% 1.07
May 4 19:00:00 - 19:59:59 1352 6.2% 0.383.7 MB 5.6% 1.04
May 4 20:00:00 - 20:59:59 1164 5.4% 0.323.0 MB 4.6% 0.86
May 4 21:00:00 - 21:59:59 1116 5.1% 0.313.1 MB 4.7% 0.87
May 4 22:00:00 - 22:59:59 1192 5.5% 0.334.7 MB 7.2% 1.34
May 4 23:00:00 - 23:59:59 997 4.6% 0.282.9 MB 4.4% 0.82
May 5 00:00:00 - 00:00:02 20 0.1% 10.0086.2 KB 0.1% 43.10
TOTAL: 24:00:01 21721 100.0% 0.2565.4 MB 100.0% 0.78
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 11376 417 0 0 0 0 0 010959
2linuxd-out.news.weretis.net 2098 552 0 0 0 0 0 0 1546
3news.fcku.it 174 11 0 0 0 0 0 0 163
4nntp.pasdenom.info 8 0 0 1 0 0 0 0 7
TOTAL: 4 13656 980 0 1 0 0 0 012675

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 143
it-alt.sport.calcio.juventus 118
lada.talk 101
lada.auto 65
mpc.lists.freebsd.ports.bugs 37
it.comp.os.win.windows10 33
it.annunci.commerciali 31
it.annunci.immobiliari 29
it.annunci.varie 29
lada.commerce 23
mozilla.support.thunderbird 21
mpc.lists.freebsd.gnome 20
mozilla.support.firefox 18
gmane.comp.gis.openstreetmap 16
uk.radio.amateur.moderated 15
spline.eisfair 13
mozilla.support.seamonkey 13
oesterreich.tratsch 11
mpc.lists.nanog 11
alt.tv.star-trek 10
TOTAL: 108 980

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 10909
EMP (phn path) 1000
EMP (phl) 152
Too many newsgroups 37
User-issued cancel 26
Binary Payload 24
EMP (phn nph) 3
Binary: non-binary distribution 2
TOTAL: 8 12153

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 503
newsfeed.neva.ru 19
TOTAL: 2 522
TOTAL: 1 522

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 36621 3370 17253 1034 0 365 9%23:57:46
2news.fcku.it 29086 1346 17876 2087 0 60 4%23:59:53
3neva.ru 28047 28 18956 143 0 128 0%23:59:52
4eternal-september 23811 22 18735 1 0 0 0%23:59:52
5weretis.net 370 9 355 0 0 2663 2%23:58:36
6news.albasani.net 2865 0 0 0 0 23771 0%23:59:53
7news.stack.nl 2858 0 0 0 0 24560 0%23:59:53
8vc89.vc.panix.com 2842 0 0 0 0 24562 0%23:59:53
9news.killfile.org 2845 0 0 0 0 24565 0%23:59:53
10etla.org 0 0 0 0 0 21721 0%23:59:53
11news.netfront.net 2766 0 0 0 0 24424 0%23:59:52
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 132111 4775 73175 3265 0 146819 3%264:05:16
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info10.6 MB2.0 MB12.6 MB0.1 KB/s2.9 KB23:57:46
2news.fcku.it3.7 MB6.3 MB10.0 MB0.1 KB/s3.0 KB23:59:53
3neva.ru88.5 KB625.4 KB714.0 KB0.0 KB/s4.2 KB23:59:52
4eternal-september62.8 KB3.5 KB66.3 KB0.0 KB/s2.9 KB23:59:52
5weretis.net16.4 KB0.0 KB16.4 KB0.0 KB/s1.8 KB23:58:36
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
7news.stack.nl0.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.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:52
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 1214.4 MB8.9 MB23.3 MB0.0 KB/s3.0 KB264:05:16
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-188-099-023-198.188.099.pools.vodafone-ip.de 135 23.6 KB 2 0 001:03:04
TOTAL: 1 135 23.6 KB 2 0 005:16:34

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.188.099.pools.vodafone-ip.de 135 23.6 KB 2 0 001:03:04
2*.drr02.clwr.fl.frontiernet.net 1 00.0 KB 0 0 000:30:01
3*.nielsenmedia.com 9 00.0 KB 0 0 003:43:28
TOTAL: 3 145 23.6 KB 2 0 005:16:34

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
challenger.nielsenmedia.com 0.464 0.036 0.00003:43:28
dslb-188-099-023-198.188.099.pools.vodafone-ip.de 14.056 3.852 0.00001:03:04
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.052 0.004 0.00000:30:01
TOTAL: 3 14.572 3.892 0.00005:16:34

Curious NNRP server explorers [Top 20]:

SystemConn
challenger.nielsenmedia.com 9
47-192-187-101.drr02.clwr.fl.frontiernet.net 1
TOTAL: 2 10

NNRP client timeouts [Top 20]:

SystemConnPeer
challenger.nielsenmedia.com 7 0
47-192-187-101.drr02.clwr.fl.frontiernet.net 1 0
TOTAL: 2 8 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 1 50.0%
2de 1 50.0%
TOTAL: 2 2100.0%

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

NewsgroupCount
1alt.idiots 1
2de.comp.sys.mac.misc 1
TOTAL: 2 2