Daily Usenet report

Feb 7 04:15:01 -- Feb 8 04:15:02

Unknown entries from news log file:

First 50 / 3647 lines (1.4%)

Feb 7 04:50:10 newsfeed overchan[16800]: input line too long, skipping
Feb 7 04:56:41 newsfeed overchan[16800]: input line too long, skipping
Feb 7 04:57:59 newsfeed overchan[16800]: input line too long, skipping
Feb 7 05:00:14 newsfeed overchan[16800]: input line too long, skipping
Feb 7 05:47:45 newsfeed overchan[16800]: input line too long, skipping
Feb 7 05:53:02 newsfeed overchan[16800]: input line too long, skipping
Feb 7 05:54:49 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:03:54 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:03:54 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:20:09 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:20:55 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:22:05 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:25:01 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:26:11 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:29:26 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:32:10 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:33:34 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:42:17 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:42:20 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:42:25 newsfeed overchan[16800]: input line too long, skipping
Feb 7 06:42:31 newsfeed overchan[16800]: message repeated 2 times: [ input line too long, skipping]
Feb 7 07:01:30 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:06:08 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:32:56 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:32:59 newsfeed overchan[16800]: message repeated 4 times: [ input line too long, skipping]
Feb 7 07:39:20 newsfeed pgpverify[15971]: pgpverify: verification failed
Feb 7 07:39:51 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:57:37 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:59:25 newsfeed overchan[16800]: input line too long, skipping
Feb 7 07:59:42 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:02:22 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:02:56 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:06:38 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:09:57 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:10:13 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:11:35 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:12:41 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:13:00 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:13:00 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:14:31 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:15:56 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:17:16 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:17:46 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:17:53 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:18:46 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:20:12 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:20:51 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:21:34 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:26:35 newsfeed overchan[16800]: input line too long, skipping
Feb 7 08:28:44 newsfeed overchan[16800]: input line too long, skipping

Log entries by program:

Program nameLines%LinesSize%Size
inn 570956 90.5%66.2 MB 90.3%
innfeed 44213 7.0%5.1 MB 7.0%
innd 8398 1.3%1.2 MB 1.6%
overchan 3633 0.6%504.6 KB 0.7%
nnrpd 3441 0.5%321.6 KB 0.4%
controlchan 12 0.0%2.7 KB 0.0%
pgpverify 1 0.0%0.2 KB 0.0%
TOTAL: 7 630654 100.0%73.3 MB100.0%

History cache:

ReasonCount%Count
Do not exist 567070 99.7%
Negative hits 1563 0.3%
Positive hits 246 0.0%
Cache misses 34 0.0%
TOTAL: 4 568913 100.0%

INND timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article cancel00:00:00.929 0.0%90 0.000 10.322 110.000
article cleanup00:00:04.531 0.0%568000 0.002 0.008 0.016
article logging00:00:14.504 0.0%568377 0.019 0.026 0.043
article parse00:08:02.035 0.6%46237818 0.007 0.010 0.015
article write00:03:32.897 0.2%567728 0.085 0.375 0.637
artlog/artcncl00:00:00.004 0.0%89 0.000 0.045 0.429
data move00:00:54.659 0.1%50055190 0.001 0.001 0.002
hisgrep/artcncl00:00:00.392 0.0%86 0.000 4.558 21.000
hishave/artcncl00:00:00.017 0.0%90 0.000 0.189 2.600
history grep00:00:00.000 0.0%0 0.000 0.000 0.000
history lookup00:07:15.864 0.5%569944 0.150 0.765 5.067
history sync00:00:00.015 0.0%293 0.000 0.051 1.000
history write00:30:57.282 2.1%567979 0.367 3.270 14.057
hiswrite/artcncl00:00:00.002 0.0%4 0.000 0.500 1.000
idle22:06:06.059 92.1%46042385 0.805 1.728 63.890
nntp read00:16:02.620 1.1%45975797 0.017 0.021 0.027
overview write00:00:02.232 0.0%567728 0.000 0.004 0.011
perl filter00:33:52.820 2.4%567979 1.341 3.579 4.676
python filter00:00:00.000 0.0%0 0.000 0.000 0.000
site send00:00:16.783 0.0%1153568 0.010 0.015 0.023
TOTAL: 24:00:07.73923:47:23.645 99.1%----

INNfeed timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
article new00:00:00.120 0.0%53154 0.000 0.002 0.017
article prepare00:00:00.017 0.0%16396 0.000 0.001 0.023
article read00:00:12.876 0.0%4089 0.000 3.149 47.000
backlog stats00:00:00.187 0.0%151226 0.000 0.001 0.005
callbacks00:00:00.000 0.0%2 0.000 0.000 0.000
data read00:00:00.809 0.0%74065 0.000 0.011 0.046
data write00:00:01.723 0.0%56231 0.007 0.031 0.060
idle23:53:22.740 99.9%151226 264.122 568.703 1497.928
status file00:00:53.247 0.1%16709 0.243 3.187 246.993
TOTAL: 23:54:35.81823:54:31.719100.0%----

nnrpd timer:

Code regionTimePctInvokedMin(ms)Avg(ms)Max(ms)
idle00:00:22.947 73.7%2820 0.000 8.137 10050.000
newnews00:00:00.000 0.0%0 0.000 0.000 0.000
nntpwrite00:00:00.101 0.3%5957 0.000 0.017 0.500
readart00:00:00.002 0.0%20 0.000 0.100 0.500
TOTAL: 00:00:31.14000:00:23.050 74.0%----

Control commands to INND:

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

Control Channel:

SendernewgrouprmgroupOtherBad PGPDoItOK
grisbi-control@grisbi.org001101
TOTAL001101

Incoming Feeds (INN):

ServerConnectsOfferedTakenRefusedReject%AccptElapsed
1newsfeed.pionier.net.pl 85 570574 570325 0 249 99%63:35:44
2news.icm.edu.pl 51 234 34 177 23 14%16:21:22
3localhost 1448 7 7 0 0 100%00:00:00
TOTAL: 3 1584 570815 570366 177 272 99%79:57:06
Articles received by server

Incoming Volume (INN):

ServerAcceptVolDupVolRejVolTotalVol%AccVol/Art
1newsfeed.pionier.net.pl215.2 GB1.1 MB1.7 MB215.2 GB 99%395.4 KB
2news.icm.edu.pl1.3 MB11.0 KB579.6 KB1.9 MB 69%34.1 KB
3localhost9.6 KB0.0 KB0.0 KB9.6 KB100%1.4 KB
TOTAL: 3215.2 GB1.1 MB2.3 MB215.2 GB 99%395.4 KB
Incoming Volume received by server

Incoming articles:

DateArticles%ArtsArt/secSize%SizeKB/sec
Feb 7 04:15:01 - 04:59:59 4330 0.8% 1.60235.9 MB 0.1% 89.51
Feb 7 05:00:00 - 05:59:59 5452 1.0% 1.51268.0 MB 0.1% 76.23
Feb 7 06:00:00 - 06:59:59 5204 0.9% 1.45287.1 MB 0.1% 81.66
Feb 7 07:00:00 - 07:59:59 5644 1.0% 1.57282.2 MB 0.1% 80.27
Feb 7 08:00:00 - 08:59:59 5967 1.0% 1.66282.8 MB 0.1% 80.44
Feb 7 09:00:00 - 09:59:59 5524 1.0% 1.53247.9 MB 0.1% 70.52
Feb 7 10:00:00 - 10:59:59 6436 1.1% 1.79300.3 MB 0.1% 85.43
Feb 7 11:00:00 - 11:59:59 21653 3.8% 6.018.5 GB 4.0%2489.03
Feb 7 12:00:00 - 12:59:59 33565 5.9% 9.3212.9 GB 6.0%3768.58
Feb 7 13:00:00 - 13:59:59 32706 5.7% 9.0912.3 GB 5.7%3594.78
Feb 7 14:00:00 - 14:59:59 34825 6.1% 9.6713.7 GB 6.4%3989.39
Feb 7 15:00:00 - 15:59:59 30606 5.4% 8.5013.0 GB 6.0%3782.78
Feb 7 16:00:00 - 16:59:59 33461 5.9% 9.2912.8 GB 6.0%3737.76
Feb 7 17:00:00 - 17:59:59 32135 5.6% 8.9313.3 GB 6.2%3885.08
Feb 7 18:00:00 - 18:59:59 31190 5.5% 8.6613.3 GB 6.2%3882.92
Feb 7 19:00:00 - 19:59:59 30995 5.4% 8.6114.0 GB 6.5%4065.72
Feb 7 20:00:00 - 20:59:59 32812 5.8% 9.1113.6 GB 6.3%3960.69
Feb 7 21:00:00 - 21:59:59 30934 5.4% 8.5911.8 GB 5.5%3422.62
Feb 7 22:00:00 - 22:59:59 30932 5.4% 8.5911.3 GB 5.3%3303.21
Feb 7 23:00:00 - 23:59:59 24857 4.4% 6.9011.8 GB 5.5%3431.62
Feb 8 00:00:00 - 00:59:59 33125 5.8% 9.2011.6 GB 5.4%3381.48
Feb 8 01:00:00 - 01:59:59 32001 5.6% 8.8912.7 GB 5.9%3690.45
Feb 8 02:00:00 - 02:59:59 28533 5.0% 7.9312.6 GB 5.8%3665.36
Feb 8 03:00:00 - 03:59:59 28980 5.1% 8.0511.2 GB 5.2%3271.44
Feb 8 04:00:00 - 04:15:02 8349 1.5% 9.263.0 GB 1.4%3522.54
TOTAL: 24:00:01 570216 100.0% 6.60215.4 GB 100.0%2614.43
Incoming articles
Incoming articles (size)

Sites sending bad articles:

ServerTotalGroupDistDuplicUnappTooOldSiteLineOther
1newsfeed.pionier.net.pl 626 226 0 20 3 0 0 0 377
2news.icm.edu.pl 25 0 0 1 0 0 0 0 24
TOTAL: 2 651 226 0 21 3 0 0 0 401

Unwanted newsgroups [Top 20]:

NewsgroupCount
uk.railway 123
comp.mobile.android 39
comp.sys.raspberry-pi 30
it.tlc.cellulari.android 16
it.comp.os.win.windows10 7
alt.comp.software.firefox 6
nl.comp.os.linux.techniek 4
alt.comp.software.seamonkey 1
TOTAL: 8 226

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

GroupsCount
pl.praca.oferowana 2
pl.sci.matematyka 1
TOTAL: 2 3

INND Perl filter [Top 20]:

ReasonCount
EMP (md5) 11
Too many newsgroups 8
Binary in non-binary group 3
TOTAL: 3 22

Miscellaneous innd statistics [Top 10]:

EventServerNumber
Including strange strings
newsfeed.pionier.net.pl 377
news.icm.edu.pl 2
TOTAL: 2 379
TOTAL: 1 379

Outgoing Feeds (innfeed) by Articles:

ServerOfferedTakenRefusedRejectMissSpool%TookElapsed
1icm 31197 2974 14726 108 0 1 9%23:56:59
2panorama 39689 406 11538 108 2 41495 1%23:59:43
3poznan 28 1 21 0 0 0 3%02:51:42
4nask 0 0 0 0 0 14004 0%23:50:02
5okapi 0 0 0 0 0 691 0%23:50:02
TOTAL: 5 70914 3381 26285 216 2 56191 4%98:28:28
Outgoing feeds (innfeed) by Articles

Outgoing Feeds (innfeed) by Volume:

ServerAcceptVolRejectVolTotalVolVolume/secVol/ArtElapsed
1icm10.6 MB316.7 KB10.9 MB0.1 KB/s3.6 KB23:56:59
2panorama1.2 MB374.3 KB1.6 MB0.0 KB/s3.2 KB23:59:43
3poznan11.6 KB0.0 KB11.6 KB0.0 KB/s11.6 KB02:51:42
4nask0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
5okapi0.0 KB0.0 KB0.0 KB0.0 KB/s023:50:02
TOTAL: 511.8 MB691.1 KB12.5 MB0.0 KB/s3.6 KB98:28:28
Outgoing feeds (innfeed) by Volume

NNRP readership statistics:

SystemConnArtsSizeGroupsPostRejElapsed
1web.man.lodz.pl 289 2018.1 KB 10 0 000:00:11
2isvcs01.man.lodz.pl 7 00.0 KB 0 7 000:00:00
TOTAL: 2 296 2018.1 KB 10 7 000:00:31

NNRP readership statistics (by domain):

SystemConnArtsSizeGroupsPostRejElapsed
1*.man.lodz.pl 296 2018.1 KB 10 7 000:00:11
2*.do.binaryedge.ninja 1 00.0 KB 0 0 000:00:02
3*.ip.linodeusercontent.com 11 00.0 KB 0 0 000:00:02
4unresolved 104 00.0 KB 0 0 000:00:13
TOTAL: 4 412 2018.1 KB 10 7 000:00:31

NNRP total resource statistics [Top 20]:

SystemUser(ms)System(ms)Idle(ms)Elapsed
web.man.lodz.pl 1.092 1.466 0.00000:00:11
192.3.251.169 0.007 0.000 0.00000:00:10
80.111.142.213 0.014 0.021 0.00000:00:02
178-79-148-229.ip.linodeusercontent.com 0.052 0.050 0.00000:00:02
jerry-se-do-ap-central-scanners-38.do.binaryedge.ninja 0.004 0.004 0.00000:00:02
212.51.192.185 0.315 0.423 0.00000:00:00
isvcs01.man.lodz.pl 0.025 0.047 0.00000:00:00
45.227.254.48 0.000 0.014 0.00000:00:00
92.255.85.215 0.000 0.007 0.00000:00:00
? 0.000 0.007 0.00000:00:00
TOTAL: 10 1.509 2.039 0.00000:00:31

Curious NNRP server explorers [Top 20]:

SystemConn
212.51.192.185 96
178-79-148-229.ip.linodeusercontent.com 11
80.111.142.213 4
45.227.254.48 2
192.3.251.169 1
jerry-se-do-ap-central-scanners-38.do.binaryedge.ninja 1
92.255.85.215 1
TOTAL: 7 116

NNRP gethostbyaddr failures [Top 20]:

SystemConn
? (can't getpeername) 1
TOTAL: 1 1

NNRP unrecognized commands (by host):

SystemConn
178-79-148-229.ip.linodeusercontent.com 7
45.227.254.48 2
92.255.85.215 1
TOTAL: 3 10

NNRP unrecognized commands (by command):

CommandCount
#003 3
À#024 2
#026#003#001 2
#026#003#001#002 2
#007ݶ p\«#014ÖÉ#023k`~ÓÖ#025­6#016È#007qV)…aÙ˜.zÀ#031K#021Å 1
TOTAL: 5 10

NNRP client timeouts [Top 20]:

SystemConnPeer
178-79-148-229.ip.linodeusercontent.com 2 2
45.227.254.48 2 2
92.255.85.215 1 1
TOTAL: 3 5 5

Newsgroup request counts (by category):

CategoryCountPct
1pl 20100.0%
TOTAL: 1 20100.0%

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

NewsgroupCount
1pl.comp.pecet 12
2pl.comp.os.ms-windows.winnt 8
TOTAL: 2 20