Daily Usenet report for paganini.linuxd.org

Apr 11 00:00:02 -- Apr 12 00:00:12

Unknown entries from news log file:

First 15 / 15 lines (100.0%)

Apr 11 00:00:09 paganini nnrpd[9902]: ceres.etla.org noperm ihave without permission
Apr 11 00:00:39 paganini nnrpd[10072]: 2001:ba8:1f1:f1ef::2 noperm ihave without permission
Apr 11 00:45:16 paganini overchan[839]: input line too long, skipping
Apr 11 00:49:10 paganini overchan[839]: input line too long, skipping
Apr 11 00:51:21 paganini overchan[839]: input line too long, skipping
Apr 11 01:13:44 paganini innd: filter: EMP database could not be dumped: File o directory non esistente
Apr 11 01:13:44 paganini innd: SERVER execv /usr/lib/news/bin/innd
Apr 11 01:13:44 paganini innd: logging hist stats to /var/spool/news/www/inn.status
Apr 11 01:13:54 paganini overchan[21425]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Apr 11 01:18:02 paganini innd: filter: EMP database could not be dumped: File o directory non esistente
Apr 11 01:18:04 paganini innd: SERVER execv /usr/lib/news/bin/innd
Apr 11 01:18:04 paganini innd: logging hist stats to /var/spool/news/www/inn.status
Apr 11 01:18:10 paganini overchan[22256]: overchan is running while innd is creating overview data (you can ignore this message if you are running makehistory -F)
Apr 11 04:20:58 paganini overchan[22256]: input line too long, skipping
Apr 11 09:41:29 paganini overchan[22256]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 42769 60.8%10.0 MB 75.0%
innd 17713 25.2%2.1 MB 15.6%
innfeed 9586 13.6%1.2 MB 9.2%
nocem 152 0.2%19.3 KB 0.1%
nnrpd 70 0.1%7.5 KB 0.1%
overchan 9 0.0%1.1 KB 0.0%
controlchan 2 0.0%0.1 KB 0.0%
TOTAL: 7 70301 100.0%13.4 MB100.0%

History cache:

ReasonCount%Count
Positive hits 290850 54.6%
Negative hits 175419 32.9%
Do not exist 42116 7.9%
Cache misses 24167 4.5%
TOTAL: 4 532552 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.508 0.0%210 0.000 2.419 47.500
article cleanup00:00:00.298 0.0%42457 0.000 0.007 0.025
article logging00:00:00.805 0.0%42553 0.000 0.019 0.879
article parse00:00:01.366 0.0%168444 0.000 0.008 0.023
article write00:00:44.905 0.1%26635 0.053 1.686 34.716
artlog/artcncl00:00:00.002 0.0%205 0.000 0.010 0.143
artlog/artparse00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.370 0.0%620785 0.000 0.001 0.003
hisgrep/artcncl00:00:00.002 0.0%204 0.000 0.010 0.100
hishave/artcncl00:00:00.011 0.0%210 0.000 0.052 3.000
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:00:31.360 0.0%538530 0.013 0.058 0.491
history sync00:00:00.209 0.0%358 0.000 0.584 46.667
history write00:00:53.534 0.1%42427 0.055 1.262 8.193
hiswrite/artcncl00:00:00.000 0.0%6 0.000 0.000 0.000
idle23:54:19.372 99.5%605385 18.538 142.156 412.284
nntp read00:00:03.620 0.0%619435 0.002 0.006 0.010
overview write00:00:27.145 0.0%26635 0.029 1.019 3.566
perl filter00:02:34.433 0.2%42427 1.165 3.640 41.557
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:03.038 0.0%83595 0.015 0.036 0.153
TOTAL: 24:00:49.37023:59:40.978 99.9%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.065 0.0%34636 0.000 0.002 0.013
article prepare00:00:00.018 0.0%188192 0.000 0.000 0.006
article read00:00:07.705 0.0%29833 0.000 0.258 11.893
backlog stats00:00:00.250 0.0%610880 0.000 0.000 0.001
callbacks00:00:00.000 0.0%7 0.000 0.000 0.000
data read00:00:02.743 0.0%397988 0.002 0.007 0.013
data write00:00:04.790 0.0%387573 0.006 0.012 0.021
idle23:50:58.773 99.9%610880 31.367 140.549 408.593
status file00:00:52.526 0.1%3169 0.000 16.575 110.000
TOTAL: 23:52:16.51323:52:06.870100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:31:53.295 99.3%120 37.333 15944.125 36140.480
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:02.210 0.1%91608 0.000 0.024 1.071
readart00:00:00.001 0.0%4 0.250 0.250 0.250
TOTAL: 00:32:06.88000:31:55.506 99.4%----

Control commands to INND:

CommandNumber
L 1
flush 1
flushlogs 2
go 2
logmode 2
mode 148
name 2
pause 2
paused 2
reload 2
reserve 2
throttle 4
xexec 2
TOTAL: 13 172

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1linuxd-out.news.weretis.net 4 57972 8822 46586 2564 15%48:47:21
2feeder.eternal-september.org 9 38770 4904 31309 2557 12%25:06:10
3vc89.vc.panix.com 2 8362 4345 3697 320 51%23:48:26
4newsfeed.neva.ru 149 89827 3047 79207 7573 3%71:51:17
5news.albasani.net 5 30182 2070 26888 1224 6%23:14:29
6news-out.mixmin.net 7 31821 1999 28359 1463 6%24:41:28
7etla.org 6 48606 1265 47341 0 2%22:37:19
8news.netfront.net 3 46667 74 46514 79 0%26:51:38
9news.fcku.it 557 12 8 0 4 66%02:36:40
10localhost 25 0 0 0 0 0%00:00:00
11nntp.pasdenom.info 2 0 0 0 0 0%00:03:00
12news.stack.nl 1 0 0 0 0 0%00:10:00
TOTAL: 12 770 352219 26534 309901 15784 7%269:47:48
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1feeder.eternal-september.org74.4 MB11.8 KB27.3 MB101.7 MB 73%14.0 KB
2news.albasani.net38.5 MB0.0 KB51.1 MB89.6 MB 42%27.9 KB
3linuxd-out.news.weretis.net30.9 MB0.0 KB20.6 MB51.5 MB 60%4.6 KB
4vc89.vc.panix.com15.5 MB95.4 KB4.5 MB20.1 MB 77%4.4 KB
5newsfeed.neva.ru8.1 MB154.1 KB64.9 MB73.2 MB 11%7.1 KB
6news-out.mixmin.net4.6 MB0.0 KB2.3 MB6.9 MB 66%2.0 KB
7etla.org4.5 MB0.0 KB0.0 KB4.5 MB100%3.6 KB
8news.netfront.net151.1 KB0.0 KB7.0 MB7.2 MB 2%48.1 KB
9news.fcku.it10.6 KB0.0 KB2.7 KB13.2 KB 79%1.1 KB
10localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
11nntp.pasdenom.info0.0 KB0.0 KB0.0 KB0.0 KB 0%0
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 12176.6 MB261.3 KB177.7 MB354.6 MB 49%8.6 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Apr 11 00:00:02 - 00:59:59 1001 3.8% 0.282.4 MB 1.4% 0.69
Apr 11 01:00:00 - 01:59:59 1047 3.9% 0.294.3 MB 2.4% 1.21
Apr 11 02:00:00 - 02:59:59 799 3.0% 0.223.0 MB 1.7% 0.85
Apr 11 03:00:00 - 03:59:59 619 2.3% 0.171.6 MB 0.9% 0.46
Apr 11 04:00:00 - 04:59:59 514 1.9% 0.144.1 MB 2.3% 1.16
Apr 11 05:00:00 - 05:59:59 562 2.1% 0.161.4 MB 0.8% 0.39
Apr 11 06:00:00 - 06:59:59 650 2.4% 0.182.1 MB 1.2% 0.60
Apr 11 07:00:00 - 07:59:59 615 2.3% 0.173.7 MB 2.1% 1.05
Apr 11 08:00:00 - 08:59:59 838 3.1% 0.233.1 MB 1.8% 0.88
Apr 11 09:00:00 - 09:59:59 1067 4.0% 0.304.2 MB 2.4% 1.20
Apr 11 10:00:00 - 10:59:59 1114 4.2% 0.315.9 MB 3.3% 1.67
Apr 11 11:00:00 - 11:59:59 1141 4.3% 0.322.9 MB 1.6% 0.82
Apr 11 12:00:00 - 12:59:59 1175 4.4% 0.332.9 MB 1.6% 0.83
Apr 11 13:00:00 - 13:59:59 1239 4.6% 0.343.1 MB 1.7% 0.87
Apr 11 14:00:00 - 14:59:59 1261 4.7% 0.353.0 MB 1.7% 0.84
Apr 11 15:00:00 - 15:59:59 1392 5.2% 0.3935.2 MB 19.9% 10.00
Apr 11 16:00:00 - 16:59:59 1559 5.8% 0.435.0 MB 2.9% 1.43
Apr 11 17:00:00 - 17:59:59 1637 6.1% 0.4530.0 MB 17.0% 8.53
Apr 11 18:00:00 - 18:59:59 1659 6.2% 0.4640.5 MB 22.9% 11.51
Apr 11 19:00:00 - 19:59:59 1475 5.5% 0.414.5 MB 2.5% 1.27
Apr 11 20:00:00 - 20:59:59 1321 5.0% 0.373.9 MB 2.2% 1.10
Apr 11 21:00:00 - 21:59:59 1364 5.1% 0.383.5 MB 2.0% 1.00
Apr 11 22:00:00 - 22:59:59 1364 5.1% 0.383.5 MB 2.0% 0.99
Apr 11 23:00:00 - 23:59:59 1250 4.7% 0.353.0 MB 1.7% 0.84
Apr 12 00:00:00 - 00:00:12 19 0.1% 1.5833.9 KB 0.0% 2.83
TOTAL: 24:00:10 26682 100.0% 0.31176.6 MB 100.0% 2.09
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 7598 1351 0 18 1 0 0 0 6228
2feeder.eternal-september.org 2579 290 0 6 0 0 0 0 2283
3linuxd-out.news.weretis.net 2568 1417 0 0 0 0 0 0 1151
4news-out.mixmin.net 1441 11 0 0 0 0 0 0 1430
5news.albasani.net 1265 97 0 0 0 0 0 0 1168
6vc89.vc.panix.com 343 0 0 6 0 0 0 0 337
7news.netfront.net 80 0 0 0 0 0 0 0 80
8news.fcku.it 4 4 0 0 0 0 0 0 0
9etla.org 3 0 0 0 0 0 0 0 3
TOTAL: 9 15881 3170 0 30 1 0 0 012680

Unwanted newsgroups [Top 20]:

NewsgroupCount
mailing.freebsd.pkg-fallout 500
fa.linux.kernel 305
ut.test 204
alt.politics 130
it-alt.sport.calcio.juventus 115
mailing.freebsd.ports-bugs 100
mpc.lists.freebsd.ports.bugs 99
alt.windows7.general 99
mailing.freebsd.svn-ports-all 84
mailing.freebsd.svn-ports-head 84
lada.auto 71
pld.cvs.commit 69
lada.talk 69
alt.politics.scorched-earth 62
alt.survival 49
mailing.freebsd.svn-src-all 49
fa.freebsd.cvs-all 49
mailing.freebsd.svn-src-head 43
gmane.linux.alsa.devel 38
mozilla.support.seamonkey 37
TOTAL: 225 3170

Supposedly-moderated groups with unmoderated postings [Top 20]:

GroupsCount
linux.samba 1
TOTAL: 1 1

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 8319
EMP (phn path) 2721
EMP (phl) 1073
PFM bot misc 109
Too many newsgroups 96
User-issued cancel 59
EMP (phn nph) 59
Binary: misplaced rar 45
Binary Image: misplaced jpg 39
Binary: non-binary distribution 30
Binary Payload 15
Binary: misplaced PAR2 9
Binary: misplaced zip 3
HTML post 3
Too many newsgroups (low_xpost) 1
Binary: misplaced par2 1
Binary: misplaced nzb 1
hierarchy violation - crosspost outside cl 1
TOTAL: 18 12584

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1vc89.vc.panix.com 30253 2120 11490 13134 2 67 7%24:04:57
2news.netfront.net 47972 1134 25398 42 1 447 2%23:58:57
3news.fcku.it 26254 789 0 24428 24 7214 3%22:35:58
4etla.org 31421 532 26162 32 0 106 1%24:03:21
5news.killfile.org 42802 194 24148 2325 1 63 0%23:59:45
6nntp.pasdenom.info 31195 173 24277 415 0 43 0%22:37:04
7neva.ru 26719 51 23189 41 0 12 0%24:05:29
8news.albasani.net 27878 11 22680 1727 0 36 0%23:59:27
9news.stack.nl 32182 9 26330 22 0 171 0%24:00:51
10eternal-september 22546 8 21798 3 0 0 0%24:03:33
11weretis.net 18152 8 17419 0 0 0 0%24:00:00
12pasdenom 0 0 0 0 0 121 0%00:04:08
13news.mixmin.net 0 0 0 0 0 0 0%00:13:58
TOTAL: 13 337374 5029 222891 42169 28 8280 1%261:47:28
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1news.netfront.net107.2 MB1.1 MB108.2 MB1.3 KB/s94.2 KB23:58:57
2etla.org106.3 MB99.0 KB106.4 MB1.3 KB/s193.1 KB24:03:21
3vc89.vc.panix.com6.1 MB102.9 MB108.9 MB1.3 KB/s7.3 KB24:04:57
4news.fcku.it1.7 MB171.0 MB172.6 MB2.2 KB/s7.0 KB22:35:58
5nntp.pasdenom.info701.3 KB3.5 MB4.1 MB0.1 KB/s7.2 KB22:37:04
6news.killfile.org456.8 KB38.0 MB38.4 MB0.5 KB/s15.6 KB23:59:45
7neva.ru115.8 KB248.4 KB364.1 KB0.0 KB/s4.0 KB24:05:29
8news.stack.nl28.6 KB38.5 KB67.1 KB0.0 KB/s2.2 KB24:00:51
9news.albasani.net19.6 KB72.6 MB72.6 MB0.9 KB/s42.8 KB23:59:27
10eternal-september10.6 KB4.3 KB15.0 KB0.0 KB/s1.4 KB24:03:33
11weretis.net10.6 KB0.0 KB10.6 KB0.0 KB/s1.3 KB24:00:00
12pasdenom0.0 KB0.0 KB0.0 KB0.0 KB/s000:04:08
13news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:13:58
TOTAL: 13222.4 MB389.4 MB611.8 MB0.7 KB/s13.3 KB261:47:28
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
195.234.22.157 1 46.4 KB 2 0 000:01:56
TOTAL: 1 1 46.4 KB 2 0 000:32:06

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1unresolved 1 46.4 KB 2 0 000:01:56
2? 1 00.0 KB 0 0 000:00:00
3*.47-151.net24.it 1 00.0 KB 0 0 000:30:07
4*.etla.org 1 00.0 KB 0 0 000:00:00
5*.range109-156.btcentralplus.com 1 00.0 KB 0 0 000:00:02
TOTAL: 5 5 46.4 KB 2 0 000:32:06

NNRP auth users [Top 20]:

UserConn
ivo@paganini.linuxd.org 2
TOTAL: 1 2

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
adsl-ull-94-61.47-151.net24.it 0.076 0.012 0.00200:30:07
95.234.22.157 0.084 0.008 0.00200:01:56
host109-156-62-126.range109-156.btcentralplus.com 0.144 0.040 0.00000:00:02
ceres.etla.org 0.064 0.008 0.00000:00:00
2001:ba8:1f1:f1ef::2 0.068 0.008 0.00000:00:00
TOTAL: 5 0.436 0.076 0.00400:32:06

Curious NNRP server explorers [Top 20]:

SystemConn
adsl-ull-94-61.47-151.net24.it 1
ceres.etla.org 1
2001:ba8:1f1:f1ef::2 1
host109-156-62-126.range109-156.btcentralplus.com 1
TOTAL: 4 4

NNRP client timeouts [Top 20]:

SystemConnPeer
adsl-ull-94-61.47-151.net24.it 1 0
TOTAL: 1 1 0

Newsgroup request counts (by category):

CategoryCountPct
1it 4100.0%
TOTAL: 1 4100.0%

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

NewsgroupCount
1it.comp.os.linux.ubuntu 2
2it.news.net-abuse 2
TOTAL: 2 4