Daily Usenet report for paganini.linuxd.org

Dec 5 00:00:02 -- Dec 6 00:00:01

Unknown entries from news log file:

First 25 / 25 lines (100.0%)

Dec 5 03:34:42 paganini overchan[11290]: input line too long, skipping
Dec 5 03:50:40 paganini overchan[11290]: input line too long, skipping
Dec 5 04:54:21 paganini overchan[11290]: input line too long, skipping
Dec 5 05:14:51 paganini overchan[11290]: input line too long, skipping
Dec 5 05:24:20 paganini overchan[11290]: input line too long, skipping
Dec 5 06:24:47 paganini overchan[11290]: input line too long, skipping
Dec 5 06:25:51 paganini overchan[11290]: input line too long, skipping
Dec 5 06:31:50 paganini overchan[11290]: input line too long, skipping
Dec 5 06:35:48 paganini overchan[11290]: input line too long, skipping
Dec 5 06:36:48 paganini overchan[11290]: input line too long, skipping
Dec 5 06:39:41 paganini overchan[11290]: input line too long, skipping
Dec 5 06:47:22 paganini overchan[11290]: input line too long, skipping
Dec 5 06:51:38 paganini overchan[11290]: input line too long, skipping
Dec 5 06:56:03 paganini overchan[11290]: input line too long, skipping
Dec 5 07:07:31 paganini overchan[11290]: input line too long, skipping
Dec 5 07:19:54 paganini overchan[11290]: input line too long, skipping
Dec 5 07:23:51 paganini overchan[11290]: input line too long, skipping
Dec 5 07:58:01 paganini overchan[11290]: input line too long, skipping
Dec 5 09:16:49 paganini overchan[11290]: input line too long, skipping
Dec 5 09:54:27 paganini overchan[11290]: input line too long, skipping
Dec 5 13:29:43 paganini overchan[11290]: input line too long, skipping
Dec 5 15:08:48 paganini overchan[11290]: input line too long, skipping
Dec 5 16:27:14 paganini overchan[11290]: input line too long, skipping
Dec 5 19:01:40 paganini overchan[11290]: input line too long, skipping
Dec 6 00:00:01 paganini overchan[4174]: 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 103501 58.0%12.8 MB 49.7%
inn 44171 24.8%9.2 MB 35.7%
innd 21159 11.9%2.7 MB 10.5%
nnrpd 9423 5.3%1.0 MB 4.0%
nocem 123 0.1%22.2 KB 0.1%
overchan 26 0.0%3.7 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 178404 100.0%25.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 151773 53.3%
Negative hits 89293 31.4%
Do not exist 43575 15.3%
Cache misses 81 0.0%
TOTAL: 4 284722 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.666 0.0%187 0.000 3.561 300.000
article cleanup00:00:00.296 0.0%43560 0.000 0.007 0.022
article logging00:00:00.451 0.0%43982 0.000 0.010 0.260
article parse00:00:01.235 0.0%101765 0.000 0.012 0.029
article write00:01:00.133 0.1%23665 0.100 2.541 17.495
artlog/artcncl00:00:00.003 0.0%173 0.000 0.017 0.333
data move00:00:00.191 0.0%291778 0.000 0.001 0.004
hisgrep/artcncl00:00:00.004 0.0%185 0.000 0.022 1.000
hishave/artcncl00:00:00.003 0.0%187 0.000 0.016 0.250
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:14.060 0.0%285297 0.019 0.049 0.559
history sync00:00:00.052 0.0%402 0.000 0.129 10.000
history write00:00:30.887 0.0%43560 0.030 0.709 3.341
hiswrite/artcncl00:00:00.000 0.0%2 0.000 0.000 0.000
idle23:56:28.611 99.7%288956 175.939 298.276 1336.996
nntp read00:00:02.295 0.0%300420 0.003 0.008 0.018
overview write00:00:23.881 0.0%23665 0.015 1.009 4.835
perl filter00:01:39.480 0.1%43560 0.919 2.284 3.785
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.544 0.0%71320 0.000 0.022 0.082
TOTAL: 24:00:59.87824:00:23.792100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.116 0.0%56422 0.000 0.002 0.016
article prepare00:00:00.003 0.0%19692 0.000 0.000 0.025
article read00:00:04.451 0.0%4465 0.000 0.997 138.800
backlog stats00:00:00.201 0.0%376772 0.000 0.001 0.002
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.575 0.0%205658 0.001 0.008 0.015
data write00:00:03.104 0.0%182393 0.007 0.017 0.033
idle23:42:02.794 99.2%376772 29.473 226.457 463.390
status file00:10:17.151 0.7%43263 6.596 14.265 51.096
TOTAL: 23:52:50.88323:52:29.395100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle07:06:26.516 99.8%16525 27.667 1548.352600128.333
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.300 0.0%33453 0.000 0.009 0.167
readart00:00:07.032 0.0%5497 0.258 1.279 36.000
TOTAL: 07:07:21.38107:06:33.848 99.8%----

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 3 54296 20515 30669 3112 37%48:22:09
2newsfeed.neva.ru 149 91812 2674 72533 16605 2%71:52:56
3news.fcku.it 2 10898 433 10310 155 3%24:58:42
4nntp.pasdenom.info 4 30353 63 30233 57 0%18:39:30
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 183 187359 23685 143745 19929 12%163:53:17
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net61.8 MB0.0 KB24.0 MB85.8 MB 72%3.7 KB
2newsfeed.neva.ru8.0 MB0.0 KB154.9 MB162.8 MB 4%8.6 KB
3news.fcku.it1.6 MB0.0 KB1.9 MB3.6 MB 45%6.2 KB
4nntp.pasdenom.info295.3 KB0.0 KB1.0 MB1.3 MB 22%11.1 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 571.6 MB0.0 KB181.9 MB253.5 MB 28%6.0 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Dec 5 00:00:02 - 00:59:59 962 4.1% 0.272.9 MB 4.0% 0.82
Dec 5 01:00:00 - 01:59:59 777 3.3% 0.222.8 MB 3.9% 0.80
Dec 5 02:00:00 - 02:59:59 543 2.3% 0.151.9 MB 2.7% 0.55
Dec 5 03:00:00 - 03:59:59 420 1.8% 0.121.5 MB 2.1% 0.43
Dec 5 04:00:00 - 04:59:59 429 1.8% 0.121.5 MB 2.1% 0.42
Dec 5 05:00:00 - 05:59:59 415 1.8% 0.121.3 MB 1.8% 0.37
Dec 5 06:00:00 - 06:59:59 469 2.0% 0.131.7 MB 2.4% 0.48
Dec 5 07:00:00 - 07:59:59 487 2.1% 0.142.0 MB 2.7% 0.56
Dec 5 08:00:00 - 08:59:59 667 2.8% 0.192.0 MB 2.8% 0.57
Dec 5 09:00:00 - 09:59:59 979 4.1% 0.272.3 MB 3.3% 0.67
Dec 5 10:00:00 - 10:59:59 1147 4.9% 0.323.0 MB 4.2% 0.87
Dec 5 11:00:00 - 11:59:59 1142 4.8% 0.323.2 MB 4.4% 0.91
Dec 5 12:00:00 - 12:59:59 1073 4.5% 0.303.1 MB 4.3% 0.88
Dec 5 13:00:00 - 13:59:59 1220 5.2% 0.343.5 MB 4.9% 1.01
Dec 5 14:00:00 - 14:59:59 1287 5.4% 0.364.2 MB 5.8% 1.19
Dec 5 15:00:00 - 15:59:59 1213 5.1% 0.343.1 MB 4.3% 0.88
Dec 5 16:00:00 - 16:59:59 1400 5.9% 0.393.9 MB 5.4% 1.10
Dec 5 17:00:00 - 17:59:59 1393 5.9% 0.394.0 MB 5.5% 1.13
Dec 5 18:00:00 - 18:59:59 1495 6.3% 0.424.4 MB 6.1% 1.24
Dec 5 19:00:00 - 19:59:59 1391 5.9% 0.394.6 MB 6.4% 1.30
Dec 5 20:00:00 - 20:59:59 1221 5.2% 0.343.6 MB 4.9% 1.01
Dec 5 21:00:00 - 21:59:59 1107 4.7% 0.313.9 MB 5.4% 1.10
Dec 5 22:00:00 - 22:59:59 1273 5.4% 0.353.5 MB 4.9% 1.01
Dec 5 23:00:00 - 23:59:59 1136 4.8% 0.324.0 MB 5.5% 1.13
Dec 6 00:00:00 - 00:00:01 3 0.0% 3.008.0 KB 0.0% 7.95
TOTAL: 23:59:59 23649 100.0% 0.2771.8 MB 100.0% 0.85
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 16613 306 0 0 0 0 0 016307
2linuxd-out.news.weretis.net 3491 521 0 0 0 0 0 0 2970
3news.fcku.it 193 0 0 0 0 0 0 0 193
4nntp.pasdenom.info 50 0 0 0 0 0 0 0 50
TOTAL: 4 20347 827 0 0 0 0 0 019520

Unwanted newsgroups [Top 20]:

NewsgroupCount
lada.talk 125
lada.auto 96
alt.survival 48
it-alt.sport.calcio.juventus 44
mozilla.support.thunderbird 40
gmane.linux.alsa.devel 34
mozilla.support.seamonkey 30
alt.windows7.general 28
ut.test 20
mpc.lists.nanog 16
it.comp.os.win.windows10 15
alt.computer.workshop 13
mozilla.support.firefox 13
alt.pl.nieruchomosci.gielda 12
gmane.linux.kernel 11
lada.kitchen 11
spline.eisfair 11
alt.2600.debate.winged-personifications 11
mozilla.dev.security.policy 10
lada.commerce 10
TOTAL: 119 827

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 15496
EMP (phn path) 2911
EMP (phl) 417
EMP (phn nph) 173
Too many newsgroups 41
Binary Payload 18
User-issued cancel 16
Binary Image: misplaced jpg 9
Binary: non-binary distribution 7
HTML post 7
User-issued spam cancel 3
TOTAL: 11 19098

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 395
newsfeed.neva.ru 27
TOTAL: 2 422
TOTAL: 1 422

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 55646 966 35315 2452 0 3253 1%23:57:38
2news.fcku.it 31714 420 21952 836 0 251 1%23:59:45
3weretis.net 3481 118 3094 0 0 7 3%23:59:45
4neva.ru 30074 45 20826 78 0 123 0%23:59:44
5eternal-september 26437 29 20132 0 0 34 0%23:59:44
6etla.org 0 0 0 0 0 23649 0%23:59:45
7vc89.vc.panix.com 2833 0 0 0 0 26481 0%23:59:44
8news.netfront.net 2738 0 0 0 0 26323 0%23:59:44
9news.killfile.org 2846 0 0 0 0 26494 0%23:59:44
10news.albasani.net 2857 0 0 0 0 25630 0%23:59:44
11news.stack.nl 2849 0 0 0 0 26459 0%23:59:44
12news.mixmin.net 0 0 0 0 0 0 0%00:10:00
TOTAL: 12 161475 1578 101319 3366 0 158704 0%264:05:01
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info3.3 MB4.2 MB7.5 MB0.1 KB/s2.3 KB23:57:38
2news.fcku.it1.5 MB3.6 MB5.1 MB0.1 KB/s4.2 KB23:59:45
3weretis.net183.5 KB0.0 KB183.5 KB0.0 KB/s1.6 KB23:59:45
4neva.ru171.2 KB366.4 KB537.6 KB0.0 KB/s4.4 KB23:59:44
5eternal-september80.8 KB0.0 KB80.8 KB0.0 KB/s2.8 KB23:59:44
6etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:45
7vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
8news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
9news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
10news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
11news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:59:44
12news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
TOTAL: 125.2 MB8.2 MB13.4 MB0.0 KB/s2.8 KB264:05:01
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1mtrlpq2314w-lp140-06-70-55-73-71.dsl.bell.ca 200 67320.6 KB 44 0 000:34:49
TOTAL: 1 200 67320.6 KB 44 0 007:07:20

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.dsl.bell.ca 200 67320.6 KB 44 0 000:34:49
2*.188.110.pools.vodafone-ip.de 132 00.0 KB 0 0 000:00:26
3*.nielsenmedia.com 5 00.0 KB 0 0 001:05:39
4*.drr02.clwr.fl.frontiernet.net 14 00.0 KB 0 0 005:26:25
TOTAL: 4 351 67320.6 KB 44 0 007:07:20

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
47-192-187-101.drr02.clwr.fl.frontiernet.net 0.736 0.040 0.00005:26:25
challenger.nielsenmedia.com 0.236 0.016 0.00001:05:39
mtrlpq2314w-lp140-06-70-55-73-71.dsl.bell.ca 16.824 1.608 0.00000:34:49
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 6.824 0.540 0.00000:00:26
TOTAL: 4 24.620 2.204 0.00007:07:20

Curious NNRP server explorers [Top 20]:

SystemConn
dslb-188-110-188-212.188.110.pools.vodafone-ip.de 132
47-192-187-101.drr02.clwr.fl.frontiernet.net 14
challenger.nielsenmedia.com 5
TOTAL: 3 151

NNRP client timeouts [Top 20]:

SystemConnPeer
47-192-187-101.drr02.clwr.fl.frontiernet.net 10 0
challenger.nielsenmedia.com 2 0
TOTAL: 2 12 0

Newsgroup request counts (by category):

CategoryCountPct
1alt 27 40.3%
2rec 17 25.4%
3uk 9 13.4%
4sci 4 6.0%
5fr 3 4.5%
6comp 3 4.5%
7misc 2 3.0%
8aus 2 3.0%
TOTAL: 8 67100.0%

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

NewsgroupCount
1rec.arts.tv 14
2alt.fan.rush-limbaugh 10
3uk.politics.misc 8
4alt.california 4
5alt.happy.valley 3
6fr.soc.politique 3
7alt.society.liberalism 3
8alt.home.repair 2
9comp.lang.python 2
10sci.physics 2
11aus.politics 2
12misc.survivalism 2
13alt.usenet.kooks 1
14alt.bumbling.idiots.the.fbi 1
15alt.sports.football.pro.sd-chargers 1
16alt.sports.football.pro.car-panthers 1
17alt.russian.z1 1
18rec.aviation.soaring 1
19sci.space.policy 1
20rec.autos.sport.f1 1
21sci.physics.relativity 1
22rec.crafts.metalworking 1
23uk.legal 1
24comp.misc 1
TOTAL: 24 67