Daily Usenet report for paganini.linuxd.org

Jun 18 00:00:02 -- Jun 19 00:00:02

Unknown entries from news log file:

First 7 / 7 lines (100.0%)

Jun 18 03:30:08 paganini overchan[12403]: input line too long, skipping
Jun 18 06:54:14 paganini overchan[12403]: input line too long, skipping
Jun 18 07:03:12 paganini overchan[12403]: input line too long, skipping
Jun 18 07:21:47 paganini overchan[12403]: input line too long, skipping
Jun 18 07:28:00 paganini overchan[12403]: input line too long, skipping
Jun 18 14:02:17 paganini overchan[12403]: input line too long, skipping
Jun 19 00:00:02 paganini overchan[23695]: 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 101337 65.8%12.7 MB 55.8%
inn 25511 16.6%6.5 MB 28.5%
nnrpd 19413 12.6%2.2 MB 9.8%
innd 7674 5.0%1.3 MB 5.8%
nocem 95 0.1%16.6 KB 0.1%
overchan 8 0.0%1.5 KB 0.0%
controlchan 1 0.0%0.1 KB 0.0%
TOTAL: 7 154039 100.0%22.8 MB100.0%

History cache:

ReasonCount%Count
Positive hits 90737 57.9%
Negative hits 41005 26.2%
Do not exist 24709 15.8%
Cache misses 150 0.1%
TOTAL: 4 156601 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.715 0.0%163 0.000 4.387 15.900
article cleanup00:00:00.170 0.0%24751 0.000 0.007 0.031
article logging00:00:00.199 0.0%25199 0.000 0.008 0.024
article parse00:00:00.520 0.0%42426 0.000 0.012 0.038
article write00:00:43.677 0.1%18485 0.121 2.363 14.725
artlog/artcncl00:00:00.000 0.0%163 0.000 0.000 0.000
artlog/artwrite00:00:00.000 0.0%0 0.000 0.000 0.000
data move00:00:00.093 0.0%169905 0.000 0.001 0.004
hisgrep/artcncl00:00:00.004 0.0%163 0.000 0.025 0.500
hishave/artcncl00:00:00.004 0.0%163 0.000 0.025 0.077
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.372 0.0%156832 0.013 0.060 0.925
history sync00:00:00.012 0.0%295 0.000 0.041 0.500
history write00:00:32.018 0.0%24750 0.036 1.294 5.235
hiswrite/artcncl00:00:00.000 0.0%0 0.000 0.000 0.000
idle23:47:11.605 99.8%166009 258.092 515.825 1481.086
nntp read00:00:01.317 0.0%170343 0.002 0.008 0.017
overview write00:00:15.601 0.0%18485 0.014 0.844 8.679
perl filter00:00:49.372 0.1%24749 1.017 1.995 5.064
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:01.100 0.0%55617 0.003 0.020 0.057
TOTAL: 23:50:19.72623:49:45.779100.0%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.100 0.0%35453 0.000 0.003 0.024
article prepare00:00:00.006 0.0%31718 0.000 0.000 0.005
article read00:00:02.330 0.0%6173 0.000 0.377 8.691
backlog stats00:00:00.209 0.0%304031 0.000 0.001 0.003
callbacks00:00:00.000 0.0%0 0.000 0.000 0.000
data read00:00:01.368 0.0%165252 0.002 0.008 0.018
data write00:00:02.696 0.0%146817 0.010 0.018 0.036
idle23:43:58.494 99.3%304031 120.160 281.019 478.502
status file00:09:08.760 0.6%42324 6.874 12.966 23.292
TOTAL: 23:53:29.45723:53:13.963100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:16:58.271 99.4%19652 38.116 51.815 114.158
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.407 0.0%39934 0.000 0.010 0.041
readart00:00:00.002 0.0%5 0.400 0.400 0.400
TOTAL: 00:17:04.48300:16:58.680 99.4%----

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 5 38801 16788 21267 746 43%48:35:01
2newsfeed.neva.ru 133 53261 1355 46831 5075 2%68:04:24
3news.fcku.it 1 7459 395 6634 430 5%26:51:47
4nntp.pasdenom.info 2 16930 166 16759 5 0%23:59:11
5localhost 25 0 0 0 0 0%00:00:00
TOTAL: 5 166 116451 18704 91491 6256 16%167:30:23
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1linuxd-out.news.weretis.net67.6 MB20.9 KB2.5 MB70.1 MB 96%4.1 KB
2newsfeed.neva.ru3.9 MB0.0 KB23.3 MB27.3 MB 14%4.3 KB
3news.fcku.it2.2 MB0.0 KB7.9 MB10.1 MB 21%12.5 KB
4nntp.pasdenom.info474.1 KB0.0 KB81.8 KB555.8 KB 85%3.3 KB
5localhost0.0 KB0.0 KB0.0 KB0.0 KB 0%0
TOTAL: 574.1 MB20.9 KB33.8 MB108.0 MB 68%4.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Jun 18 00:00:02 - 00:59:59 750 4.0% 0.212.8 MB 3.8% 0.80
Jun 18 01:00:00 - 01:59:59 654 3.5% 0.182.3 MB 3.1% 0.65
Jun 18 02:00:00 - 02:59:59 585 3.1% 0.161.7 MB 2.3% 0.48
Jun 18 03:00:00 - 03:59:59 432 2.3% 0.121.4 MB 1.9% 0.39
Jun 18 04:00:00 - 04:59:59 414 2.2% 0.121.5 MB 2.0% 0.42
Jun 18 05:00:00 - 05:59:59 369 2.0% 0.102.0 MB 2.7% 0.57
Jun 18 06:00:00 - 06:59:59 477 2.6% 0.133.1 MB 4.2% 0.87
Jun 18 07:00:00 - 07:59:59 367 2.0% 0.101.7 MB 2.4% 0.50
Jun 18 08:00:00 - 08:59:59 520 2.8% 0.142.3 MB 3.1% 0.64
Jun 18 09:00:00 - 09:59:59 570 3.1% 0.162.2 MB 3.0% 0.63
Jun 18 10:00:00 - 10:59:59 782 4.2% 0.222.6 MB 3.5% 0.73
Jun 18 11:00:00 - 11:59:59 816 4.4% 0.232.3 MB 3.1% 0.65
Jun 18 12:00:00 - 12:59:59 784 4.2% 0.222.3 MB 3.1% 0.65
Jun 18 13:00:00 - 13:59:59 672 3.6% 0.192.0 MB 2.8% 0.57
Jun 18 14:00:00 - 14:59:59 887 4.8% 0.252.6 MB 3.6% 0.75
Jun 18 15:00:00 - 15:59:59 919 4.9% 0.263.6 MB 5.0% 1.03
Jun 18 16:00:00 - 16:59:59 996 5.4% 0.283.4 MB 4.6% 0.96
Jun 18 17:00:00 - 17:59:59 997 5.4% 0.283.7 MB 5.1% 1.06
Jun 18 18:00:00 - 18:59:59 1170 6.3% 0.335.0 MB 6.8% 1.41
Jun 18 19:00:00 - 19:59:59 1039 5.6% 0.293.6 MB 4.9% 1.01
Jun 18 20:00:00 - 20:59:59 1140 6.1% 0.325.3 MB 7.2% 1.50
Jun 18 21:00:00 - 21:59:59 1126 6.1% 0.318.1 MB 11.1% 2.31
Jun 18 22:00:00 - 22:59:59 1099 5.9% 0.314.4 MB 6.1% 1.26
Jun 18 23:00:00 - 23:59:59 1026 5.5% 0.283.4 MB 4.6% 0.96
Jun 19 00:00:00 - 00:00:02 8 0.0% 4.0028.9 KB 0.0% 14.44
TOTAL: 24:00:00 18599 100.0% 0.2273.2 MB 100.0% 0.87
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.neva.ru 5155 252 0 0 0 0 0 0 4903
2linuxd-out.news.weretis.net 1197 316 0 1 0 0 0 0 880
3news.fcku.it 395 9 0 0 2 0 0 0 384
4nntp.pasdenom.info 16 7 0 0 0 1 0 0 8
TOTAL: 4 6763 584 0 1 2 1 0 0 6175

Unwanted newsgroups [Top 20]:

NewsgroupCount
ut.test 92
lada.talk 59
mpc.lists.freebsd.ports.bugs 51
alt.windows7.general 31
lada.auto 24
mozilla.general 23
mozilla.support.thunderbird 21
it.comp.os.win.windows10 20
alt.politics.scorched-earth 18
gmane.linux.alsa.devel 16
mozilla.support.firefox 14
it-alt.sport.calcio.juventus 14
alt.2600 13
alt.english.usage 12
mpc.lists.freebsd.gecko 12
alt.survival 12
spline.eisfair 12
spline.eisfair.dev 8
eagle.support.eng 8
mpc.lists.nanog 8
TOTAL: 84 584

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

GroupsCount
alt.culture.china 2
TOTAL: 1 2

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 5014
EMP (phn path) 385
EMP (phl) 178
Too many newsgroups 62
EMP (phn nph) 34
User-issued cancel 16
Binary: non-binary distribution 14
Binary Payload 9
Binary Image: misplaced jpg 8
HTML post 2
Too many newsgroups (meow) 1
Too many newsgroups (low_xpost) 1
User-issued spam cancel 1
TOTAL: 13 5725

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
linuxd-out.news.weretis.net 439
newsfeed.neva.ru 8
news.fcku.it 3
TOTAL: 3 450
TOTAL: 1 450

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1nntp.pasdenom.info 31111 2636 14639 1056 1 440 8%23:59:49
2news.fcku.it 22793 1726 14183 2378 0 65 7%24:00:43
3weretis.net 2069 49 1806 0 0 1 2%24:00:06
4neva.ru 23131 25 17174 99 0 2355 0%24:07:38
5eternal-september 20810 19 15924 1 0 16 0%24:07:38
6news.albasani.net 2861 0 0 0 0 20626 0%23:58:21
7news.netfront.net 2755 0 0 0 0 21324 0%23:58:16
8news.mixmin.net 0 0 0 0 0 0 0%00:10:00
9vc89.vc.panix.com 2838 0 0 0 0 21430 0%23:58:13
10etla.org 0 0 0 0 0 18735 0%24:07:38
11news.killfile.org 2840 0 0 0 0 21433 0%23:58:26
12news.stack.nl 2855 0 0 0 0 21282 0%23:58:12
TOTAL: 12 114063 4455 63726 3534 1 127707 3%264:25:00
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1nntp.pasdenom.info8.8 MB2.2 MB10.9 MB0.1 KB/s3.0 KB23:59:49
2news.fcku.it5.1 MB7.2 MB12.3 MB0.1 KB/s3.1 KB24:00:43
3neva.ru587.9 KB569.9 KB1.1 MB0.0 KB/s9.3 KB24:07:38
4weretis.net71.7 KB0.0 KB71.7 KB0.0 KB/s1.5 KB24:00:06
5eternal-september58.2 KB3.9 KB62.2 KB0.0 KB/s3.1 KB24:07:38
6news.albasani.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:21
7news.netfront.net0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:16
8news.mixmin.net0.0 KB0.0 KB0.0 KB0.0 KB/s000:10:00
9vc89.vc.panix.com0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:13
10etla.org0.0 KB0.0 KB0.0 KB0.0 KB/s024:07:38
11news.killfile.org0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:26
12news.stack.nl0.0 KB0.0 KB0.0 KB0.0 KB/s023:58:12
TOTAL: 1214.5 MB10.0 MB24.5 MB0.0 KB/s3.1 KB264:25:00
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1dslb-088-067-084-037.088.067.pools.vodafone-ip.de 36 513.9 KB 2 0 000:17:04
TOTAL: 1 36 513.9 KB 2 0 000:17:04

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.088.067.pools.vodafone-ip.de 36 513.9 KB 2 0 000:17:04
2*.shodan.io 1 00.0 KB 0 0 000:00:00
TOTAL: 2 37 513.9 KB 2 0 000:17:04

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
dslb-088-067-084-037.088.067.pools.vodafone-ip.de 3.852 0.996 0.00000:17:04
census10.shodan.io 0.052 0.004 0.00000:00:00
TOTAL: 2 3.904 1.000 0.00000:17:04

Curious NNRP server explorers [Top 20]:

SystemConn
census10.shodan.io 1
TOTAL: 1 1

Newsgroup request counts (by category):

CategoryCountPct
1ger 3 60.0%
2de 2 40.0%
TOTAL: 2 5100.0%

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

NewsgroupCount
1ger.ct 3
2de.talk.tagesgeschehen 2
TOTAL: 2 5