Daily Usenet report for paganini.linuxd.org

Jun 3 00:00:05 -- Jun 4 00:00:03

Unknown entries from news log file:

First 2 / 2 lines (100.0%)

Jun 3 12:00:03 paganini pgpverify[18587]: pgpverify: verification failed
Jun 4 00:00:03 paganini overchan[9946]: 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 101318 50.5%12.6 MB 42.7%
nnrpd 55795 27.8%6.4 MB 21.7%
inn 34617 17.3%9.0 MB 30.6%
innd 8743 4.4%1.5 MB 5.0%
nocem 78 0.0%13.2 KB 0.0%
controlchan 4 0.0%0.5 KB 0.0%
overchan 2 0.0%0.2 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
rnews 1 0.0%0.2 KB 0.0%
TOTAL: 9 200559 100.0%29.6 MB100.0%

History cache:

ReasonCount%Count
Positive hits 114635 56.5%
Negative hits 49926 24.6%
Do not exist 33722 16.6%
Cache misses 4775 2.4%
TOTAL: 4 203058 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.885 0.0%172 0.000 5.145 221.000
article cleanup00:00:00.223 0.0%33669 0.000 0.007 0.029
article logging00:00:00.246 0.0%34269 0.000 0.007 0.030
article parse00:00:00.620 0.0%50855 0.000 0.012 0.035
article write00:00:55.068 0.1%26191 0.149 2.103 8.434
artlog/artcncl00:00:00.001 0.0%166 0.000 0.006 0.021
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.139 0.0%200223 0.000 0.001 0.018
hisgrep/artcncl00:00:00.002 0.0%142 0.000 0.014 1.000
hishave/artcncl00:00:00.040 0.0%172 0.000 0.233 16.000
hissync/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:09.416 0.0%202886 0.014 0.046 2.800
history sync00:00:00.075 0.0%391 0.000 0.192 15.000
history write00:00:30.931 0.0%33655 0.000 0.919 4.711
hiswrite/artcncl00:00:00.000 0.0%30 0.000 0.000 0.000
idle23:57:34.724 99.8%193949 43.594 444.729 11641.235
nntp read00:00:01.494 0.0%200411 0.000 0.007 0.017
overview write00:00:18.399 0.0%26191 0.015 0.702 24.750
perl filter00:00:57.635 0.1%33655 1.052 1.713 27.750
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:02.084 0.0%78747 0.000 0.026 0.058
TOTAL: 24:01:06.40224:00:31.982100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.105 0.0%51446 0.000 0.002 0.016
article prepare00:00:00.002 0.0%33152 0.000 0.000 0.004
article read00:00:04.140 0.0%6521 0.000 0.635 29.032
backlog stats00:00:00.265 0.0%360105 0.000 0.001 0.004
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.586 0.0%198413 0.002 0.008 0.019
data write00:00:03.172 0.0%173061 0.008 0.018 0.029
idle23:43:06.250 99.3%360105 15.916 237.115 829.869
status file00:09:39.012 0.7%42358 8.202 13.669 37.853
TOTAL: 23:53:20.04623:52:54.532100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:50:25.940 99.3%56471 37.224 53.584 97.751
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:01.118 0.0%114702 0.003 0.010 0.113
readart00:00:00.000 0.0%1 0.000 0.000 0.000
TOTAL: 00:50:47.61400:50:27.058 99.3%----

Control commands to INND:

CommandNumber
L 1
flush 6
flushlogs 2
go 1
logmode 2
mode 148
name 2
pause 1
paused 1
reload 2
TOTAL: 10 166

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
moderator@dana.de000100
TOTAL000100

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 7 55849 23807 30368 1674 42%47:48:07
2newsfeed.neva.ru 143 62195 2107 54553 5535 3%70:15:16
3news.fcku.it 3 8689 193 8307 189 2%22:38:25
4nntp.pasdenom.info 5 10012 29 9949 34 0%05:57:34
5localhost 24 0 0 0 0 0%00:00:00
TOTAL: 5 182 136745 26136 103177 7432 19%146:39:22
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net79.1 MB28.0 KB5.7 MB84.8 MB 93%3.4 KB
2newsfeed.neva.ru5.8 MB4.5 KB22.2 MB27.9 MB 20%3.7 KB
3news.fcku.it556.3 KB0.0 KB2.7 MB3.2 MB 16%8.7 KB
4nntp.pasdenom.info57.5 KB0.0 KB106.1 KB163.5 KB 35%2.6 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 585.4 MB32.5 KB30.7 MB116.2 MB 73%3.5 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 3 00:00:05 - 00:59:59 6963 26.5% 1.9421.7 MB 25.3% 6.18
Jun 3 01:00:00 - 01:59:59 772 2.9% 0.212.4 MB 2.8% 0.67
Jun 3 02:00:00 - 02:59:59 660 2.5% 0.182.3 MB 2.7% 0.66
Jun 3 03:00:00 - 03:59:59 529 2.0% 0.152.5 MB 3.0% 0.73
Jun 3 04:00:00 - 04:59:59 517 2.0% 0.142.2 MB 2.5% 0.62
Jun 3 05:00:00 - 05:59:59 470 1.8% 0.132.5 MB 3.0% 0.72
Jun 3 06:00:00 - 06:59:59 488 1.9% 0.142.8 MB 3.3% 0.81
Jun 3 07:00:00 - 07:59:59 579 2.2% 0.162.3 MB 2.7% 0.65
Jun 3 08:00:00 - 08:59:59 589 2.2% 0.162.1 MB 2.5% 0.61
Jun 3 09:00:00 - 09:59:59 805 3.1% 0.222.2 MB 2.6% 0.63
Jun 3 10:00:00 - 10:59:59 829 3.1% 0.232.3 MB 2.7% 0.65
Jun 3 11:00:00 - 11:59:59 880 3.3% 0.242.9 MB 3.3% 0.81
Jun 3 12:00:00 - 12:59:59 829 3.1% 0.232.3 MB 2.6% 0.65
Jun 3 13:00:00 - 13:59:59 965 3.7% 0.273.3 MB 3.8% 0.93
Jun 3 14:00:00 - 14:59:59 1012 3.8% 0.283.2 MB 3.7% 0.92
Jun 3 15:00:00 - 15:59:59 1099 4.2% 0.313.4 MB 3.9% 0.96
Jun 3 16:00:00 - 16:59:59 1167 4.4% 0.323.5 MB 4.1% 1.01
Jun 3 17:00:00 - 17:59:59 1064 4.0% 0.303.2 MB 3.7% 0.90
Jun 3 18:00:00 - 18:59:59 960 3.6% 0.272.5 MB 3.0% 0.72
Jun 3 19:00:00 - 19:59:59 1068 4.1% 0.302.9 MB 3.4% 0.82
Jun 3 20:00:00 - 20:59:59 1026 3.9% 0.283.9 MB 4.5% 1.11
Jun 3 21:00:00 - 21:59:59 920 3.5% 0.262.6 MB 3.0% 0.74
Jun 3 22:00:00 - 22:59:59 1018 3.9% 0.283.2 MB 3.8% 0.92
Jun 3 23:00:00 - 23:59:59 1089 4.1% 0.303.4 MB 4.0% 0.98
Jun 4 00:00:00 - 00:00:03 24 0.1% 8.00191.9 KB 0.2% 63.96
TOTAL: 23:59:58 26322 100.0% 0.3085.9 MB 100.0% 1.02
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 5624 145 0 2 0 0 0 0 5477
2linuxd-out.news.weretis.net 2239 627 0 11 0 0 0 0 1601
3news.fcku.it 206 24 0 0 0 0 0 0 182
4nntp.pasdenom.info 42 2 0 1 0 0 0 0 39
TOTAL: 4 8111 798 0 14 0 0 0 0 7299

Unwanted newsgroups [Top 20]:

NewsgroupCount
it-alt.sport.calcio.juventus 173
lada.talk 101
spline.eisfair 54
alt.windows7.general 43
mozilla.support.seamonkey 30
ut.test 29
alt.survival 22
it.comp.os.win.windows10 22
lada.photo 21
mozilla.support.thunderbird 19
gmane.comp.gis.openstreetmap.tagging 16
mpc.lists.nanog 15
gmane.comp.gis.openstreetmap 14
lada.auto 12
alt.politics 12
uk.radio.amateur.moderated 12
alt.politics.scorched-earth 11
oesterreich.tratsch 9
lada.commerce 9
mozilla.dev.ui-alerts 7
TOTAL: 112 798

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5407
EMP (phn path) 903
EMP (phn nph) 124
EMP (phl) 103
Too many newsgroups 78
User-issued cancel 24
Binary Payload 18
HTML post 11
Binary Image: misplaced jpg 11
User-issued spam cancel 5
Too many newsgroups (meow) 5
Binary: non-binary distribution 2
Too many newsgroups (low_xpost) 2
Binary: misplaced par2 1
TOTAL: 14 6694

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 565
newsfeed.neva.ru 40
TOTAL: 2 605
TOTAL: 1 605

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 40164 2764 22159 1147 0 3665 6%23:58:28
2news.fcku.it 32029 1795 21892 2422 0 649 5%23:58:26
3weretis.net 2596 62 2363 0 0 0 2%23:46:33
4eternal-september 27452 25 22478 0 0 2945 0%23:58:28
5neva.ru 30689 6 23712 125 0 3944 0%23:46:30
6etla.org 0 0 0 0 0 26322 0%24:09:33
7vc89.vc.panix.com 2829 0 0 0 0 29152 0%23:59:53
8news.albasani.net 2866 0 0 0 0 28048 0%24:00:00
9news.killfile.org 2841 0 0 0 0 29164 0%24:00:08
10news.stack.nl 2859 0 0 0 0 29091 0%23:59:44
11news.netfront.net 2756 0 0 0 0 29015 0%23:59:42
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 147081 4652 92604 3694 0 181995 3%263:47:25
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info9.2 MB2.8 MB12.0 MB0.1 KB/s3.1 KB23:58:28
2news.fcku.it5.5 MB7.4 MB12.8 MB0.2 KB/s3.1 KB23:58:26
3eternal-september75.9 KB0.0 KB75.9 KB0.0 KB/s3.0 KB23:58:28
4weretis.net75.8 KB0.0 KB75.8 KB0.0 KB/s1.2 KB23:46:33
5neva.ru21.8 KB406.9 KB428.7 KB0.0 KB/s3.3 KB23:46:30
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:09:33
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:53
8news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:00
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:00:08
10news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
11news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:42
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 1214.8 MB10.5 MB25.4 MB0.0 KB/s3.1 KB263:47:25
Outgoing feeds (innfeed) by Volume

Rnews connections rejected:

ReasonConn
400 Spazio esaurito sul device writing SMstore file -- throttling 1
TOTAL: 1 1

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-088-067-084-037.088.067.pools.vodafone-ip.de 101 10.7 KB 1 0 000:50:47
TOTAL: 1 101 10.7 KB 1 0 000:50:47

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.088.067.pools.vodafone-ip.de 101 10.7 KB 1 0 000:50:47
TOTAL: 1 101 10.7 KB 1 0 000:50:47

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 10.876 2.588 0.00000:50:47
TOTAL: 1 10.876 2.588 0.00000:50:47

Newsgroup request counts (by category):

CategoryCountPct
1alt 1100.0%
TOTAL: 1 1100.0%

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

NewsgroupCount
1alt.test 1
TOTAL: 1 1