Report for Net-Statsd-Server-0.20

Back
From: metabase:user:314402c4-2aae-11df-837a-5e0a49663a4f
Subject: FAIL Net-Statsd-Server-0.20 v5.25.12 GNU/Linux
Date: 2017-04-27T17:23:29Z

This distribution has been tested as part of the CPAN Testers
project, supporting the Perl programming language.  See
http://wiki.cpantesters.org/ for more information or email
questions to cpan-testers-discuss@perl.org


--
Dear Cosimo Streppone,

This is a computer-generated report for Net-Statsd-Server-0.20
on perl 5.25.12, created by CPAN-Reporter-1.2018.

Thank you for uploading your work to CPAN.  However, there was a problem
testing your distribution.

If you think this report is invalid, please consult the CPAN Testers Wiki
for suggestions on how to avoid getting FAIL reports for missing library
or binary dependencies, unsupported operating systems, and so on:

http://wiki.cpantesters.org/wiki/CPANAuthorNotes

Sections of this report:

    * Tester comments
    * Program output
    * Prerequisites
    * Environment and other context

------------------------------
TESTER COMMENTS
------------------------------

Additional comments from tester:

none provided

------------------------------
PROGRAM OUTPUT
------------------------------

Output from '/usr/bin/make test':

PERL_DL_NONLAZY=1 "/bbbike/perl-5.25.12t/bin/perl5.25.12" "-MExtUtils::Command::MM" "-MTest::Harness" "-e" "undef *Test::Harness::Switches; test_harness(0, 'blib/lib', 'blib/arch')" t/*.t t/integration-tests/*.t
t/000-sanity.t ............................. ok
t/config.t ................................. ok
t/graphite.t ............................... ok
2017-04-27 12:52:04.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Console backend
2017-04-27 12:52:04.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:04.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:04.114955 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:04.114993 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:04.115034 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:04.429811 +0000 warn  Net::Statsd::Server: Bad line: 1 in msg "a_bad_test_value|z"
2017-04-27 12:52:05.142426 +0000 note  Net::Statsd::Server: flushing metrics
Flushing stats at Thu Apr 27 12:52:05 2017
{"counter_rates":{"statsd.packets_received":1,"statsd.bad_lines_seen":1},"gauges":{},"sets":null,"timers":{},"counters":{"statsd.bad_lines_seen":1,"statsd.packets_received":1},"pctThreshold":[90],"timer_data":{}}
t/integration-tests/bad-message.t .......... ok
2017-04-27 12:52:05.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Console backend
2017-04-27 12:52:05.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:05.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:05.936175 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:05.936211 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:05.936256 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:06.935235 +0000 note  Net::Statsd::Server: flushing metrics
Flushing stats at Thu Apr 27 12:52:06 2017
{"timers":{},"sets":null,"gauges":{},"pctThreshold":[90],"counters":{"statsd.packets_received":1,"a_test_value":100,"statsd.bad_lines_seen":0},"counter_rates":{"a_test_value":100,"statsd.bad_lines_seen":0,"statsd.packets_received":1},"timer_data":{}}
# stats.counters.statsd.packets_received.rate 1.000000 1493297526
# stats.counters.statsd.packets_received.count 1 1493297526
# stats.counters.a_test_value.rate 100.000000 1493297526
# stats.counters.a_test_value.count 100 1493297526
# stats.counters.statsd.bad_lines_seen.rate 0.000000 1493297526
# stats.counters.statsd.bad_lines_seen.count 0 1493297526
# stats.statsd.numStats 3 1493297526
# stats.statsd.graphiteStats.calculationtime 0.059000 1493297526
# stats.statsd.processing_time 0.029 1493297526
# stats.statsd.graphiteStats.last_exception 21700608 1493297526
# stats.statsd.graphiteStats.last_flush 21700608 1493297526
t/integration-tests/counts-are-valid.t ..... ok
2017-04-27 12:52:07.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:07.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:07.612981 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:07.613025 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:07.613079 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:08.612531 +0000 note  Net::Statsd::Server: flushing metrics
# First flush. Test gauge should be there.
2017-04-27 12:52:09.619204 +0000 note  Net::Statsd::Server: flushing metrics
# Second flush. deleteGauges=false should keep the test gauge.
t/integration-tests/delete-gauges-false.t .. ok
2017-04-27 12:52:11.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:11.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:11.094795 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:11.094830 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:11.094866 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:12.095627 +0000 note  Net::Statsd::Server: flushing metrics
# First flush. Test gauge should be there.
Use of uninitialized value in numeric eq (==) at t/integration-tests/delete-idle-stats.t line 40.

#   Failed test 'Got back the expected gauge value'
#   at t/integration-tests/delete-idle-stats.t line 40.
2017-04-27 12:52:13.099235 +0000 note  Net::Statsd::Server: flushing metrics
# Second flush. deleteIdleStats should have cleared the test gauge.
# Looks like you failed 1 test of 4.
t/integration-tests/delete-idle-stats.t .... 
Dubious, test returned 1 (wstat 256, 0x100)
Failed 1/4 subtests 
2017-04-27 12:52:14.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Console backend
2017-04-27 12:52:14.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:14.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:14.541837 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:14.541874 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:14.541914 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:15.582951 +0000 note  Net::Statsd::Server: flushing metrics
Flushing stats at Thu Apr 27 12:52:15 2017
{"timer_data":{},"sets":null,"timers":{},"pctThreshold":[90],"gauges":{},"counter_rates":{"statsd.bad_lines_seen":0,"statsd.packets_received":1,"a_test_value,category=web,severity=high":100},"counters":{"statsd.bad_lines_seen":0,"statsd.packets_received":1,"a_test_value,category=web,severity=high":100}}
# stats.counters.statsd.bad_lines_seen.rate 0.000000 1493297535
# stats.counters.statsd.bad_lines_seen.count 0 1493297535
# stats.counters.statsd.packets_received.rate 1.000000 1493297535
# stats.counters.statsd.packets_received.count 1 1493297535
# stats.counters.a_test_value,category=web,severity=high.rate 100.000000 1493297535
# stats.counters.a_test_value,category=web,severity=high.count 100 1493297535
# stats.statsd.numStats 3 1493297535
# stats.statsd.graphiteStats.calculationtime 0.057000 1493297535
# stats.statsd.processing_time 0.027 1493297535
# stats.statsd.graphiteStats.last_exception 34697216 1493297535
# stats.statsd.graphiteStats.last_flush 34697216 1493297535
t/integration-tests/keys-with-tags.t ....... ok
2017-04-27 12:52:16.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:16.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:16.664410 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:16.664448 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:16.664581 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
# Expected mean value is 1948.21
2017-04-27 12:52:17.659322 +0000 note  Net::Statsd::Server: flushing metrics
# stats.counters.statsd.bad_lines_seen.rate 0.000000 1493297537
# stats.counters.statsd.bad_lines_seen.count 0 1493297537
# stats.counters.statsd.packets_received.rate 100.000000 1493297537
# stats.counters.statsd.packets_received.count 100 1493297537
# stats.timers.read_latency.count_95 95.000000 1493297537
# stats.timers.read_latency.sum_squares_95 364391268.000000 1493297537
# stats.timers.read_latency.sum_squares 407333559.000000 1493297537
# stats.timers.read_latency.sum_squares_98 389548519.000000 1493297537
# stats.timers.read_latency.count_ps 100.000000 1493297537
# stats.timers.read_latency.mean_95 1896.526316 1493297537
# stats.timers.read_latency.count 100.000000 1493297537
# stats.timers.read_latency.count_99 99.000000 1493297537
# stats.timers.read_latency.sum 194821.000000 1493297537
# stats.timers.read_latency.sum_95 180170.000000 1493297537
# stats.timers.read_latency.upper_98 2931.000000 1493297537
# stats.timers.read_latency.upper 2996.000000 1493297537
# stats.timers.read_latency.sum_99 191825.000000 1493297537
# stats.timers.read_latency.mean 1948.210000 1493297537
# stats.timers.read_latency.median 1960.000000 1493297537
# stats.timers.read_latency.std 527.080056 1493297537
# stats.timers.read_latency.mean_98 1927.112245 1493297537
# stats.timers.read_latency.mean_99 1937.626263 1493297537
# stats.timers.read_latency.sum_squares_99 398357543.000000 1493297537
# stats.timers.read_latency.sum_98 188857.000000 1493297537
# stats.timers.read_latency.lower 1039.000000 1493297537
# stats.timers.read_latency.count_98 98.000000 1493297537
# stats.timers.read_latency.upper_99 2968.000000 1493297537
# stats.timers.read_latency.upper_95 2857.000000 1493297537
# stats.statsd.numStats 3 1493297537
# stats.statsd.graphiteStats.calculationtime 0.119000 1493297537
# stats.statsd.processing_time 0.302 1493297537
# stats.statsd.graphiteStats.last_exception 37003264 1493297537
# stats.statsd.graphiteStats.last_flush 37003264 1493297537
t/integration-tests/percent-threshold.t .... ok
2017-04-27 12:52:18.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Console backend
2017-04-27 12:52:18.000000 +0000 note  Net::Statsd::Server: Initializing Net::Statsd::Server::Backend::Graphite backend
2017-04-27 12:52:18.000000 +0000 info  AnyEvent: Autoloaded model 'AnyEvent::Impl::EV', using it.
2017-04-27 12:52:18.185595 +0000 note  Net::Statsd::Server: statsd server started on 0.0.0.0:40001 (v0.17)
2017-04-27 12:52:18.185631 +0000 note  Net::Statsd::Server: manager interface started on 0.0.0.0:40002
2017-04-27 12:52:18.185671 +0000 note  Net::Statsd::Server: metrics flush will happen every 1s
2017-04-27 12:52:19.187251 +0000 note  Net::Statsd::Server: flushing metrics
Flushing stats at Thu Apr 27 12:52:19 2017
{"counter_rates":{"statsd.packets_received":1,"statsd.bad_lines_seen":0},"gauges":{},"sets":null,"counters":{"statsd.packets_received":1,"statsd.bad_lines_seen":0},"timer_data":{"a_test_value":{"sum_squares":10000,"count_90":1,"mean":100,"median":"100","count":1,"mean_90":"100","std":0,"upper":"100","count_ps":1,"upper_90":"100","lower":"100","sum":"100","sum_90":"100","sum_squares_90":10000}},"timers":{"a_test_value":["100"]},"pctThreshold":["90"]}
# stats.counters.statsd.packets_received.rate 1.000000 1493297539
# stats.counters.statsd.packets_received.count 1 1493297539
# stats.counters.statsd.bad_lines_seen.rate 0.000000 1493297539
# stats.counters.statsd.bad_lines_seen.count 0 1493297539
# stats.timers.a_test_value.sum_squares 10000.000000 1493297539
# stats.timers.a_test_value.count_90 1.000000 1493297539
# stats.timers.a_test_value.mean 100.000000 1493297539
# stats.timers.a_test_value.median 100.000000 1493297539
# stats.timers.a_test_value.count 1.000000 1493297539
# stats.timers.a_test_value.mean_90 100.000000 1493297539
# stats.timers.a_test_value.std 0.000000 1493297539
# stats.timers.a_test_value.upper 100.000000 1493297539
# stats.timers.a_test_value.count_ps 1.000000 1493297539
# stats.timers.a_test_value.upper_90 100.000000 1493297539
# stats.timers.a_test_value.lower 100.000000 1493297539
# stats.timers.a_test_value.sum 100.000000 1493297539
# stats.timers.a_test_value.sum_90 100.000000 1493297539
# stats.timers.a_test_value.sum_squares_90 10000.000000 1493297539
# stats.statsd.numStats 3 1493297539
# stats.statsd.graphiteStats.calculationtime 0.102000 1493297539
# stats.statsd.processing_time 0.076 1493297539
# stats.statsd.graphiteStats.last_exception 14970880 1493297539
# stats.statsd.graphiteStats.last_flush 14970880 1493297539
t/integration-tests/timers-are-valid.t ..... ok
t/process-metrics.t ........................ ok
t/trim.t ................................... ok

Test Summary Report
-------------------
t/integration-tests/delete-idle-stats.t  (Wstat: 256 Tests: 4 Failed: 1)
  Failed test:  2
  Non-zero exit status: 1
Files=12, Tests=92, 18 wallclock secs ( 0.06 usr  0.10 sys +  2.14 cusr  0.53 csys =  2.83 CPU)
Result: FAIL
Failed 1/12 test programs. 1/92 subtests failed.
Makefile:915: recipe for target 'test_dynamic' failed
make: *** [test_dynamic] Error 255

------------------------------
PREREQUISITES
------------------------------

Prerequisite modules loaded:

requires:

    Module                Need Have    
    --------------------- ---- --------
    AnyEvent::Handle::UDP 0    0.048   
    IO::Socket::INET      0    1.35    
    JSON::XS              0    3.03    

build_requires:

    Module                Need Have    
    --------------------- ---- --------
    ExtUtils::MakeMaker   0    7.24    
    Test::More            0    1.302083

configure_requires:

    Module                Need Have    
    --------------------- ---- --------
    ExtUtils::MakeMaker   0    7.24    


------------------------------
ENVIRONMENT AND OTHER CONTEXT
------------------------------

Environment variables:

    LANG = C
    LC_ALL = de_DE.UTF-8
    PATH = /usr/local/bin:/usr/bin:/bin:/usr/local/sbin:/usr/sbin:/sbin:/home/cpansand/bin/linux-gnu:/home/cpansand/bin/sh:/home/cpansand/bin:/usr/games:/home/cpansand/devel:/home/eserte/src/srezic-misc/scripts
    PERL5LIB = 
    PERL5OPT = 
    PERL5_CPANPLUS_IS_RUNNING = 29543
    PERL5_CPAN_IS_RUNNING = 29543
    PERL5_CPAN_IS_RUNNING_IN_RECURSION = 4632,29543
    PERLDOC = -MPod::Perldoc::ToTextOverstrike
    PERL_BATCH = yes
    PERL_CANARY_STABILITY_NOPROMPT = 1
    PERL_CPAN_REPORTER_CONFIG = /var/tmp/cpansmoker-1023/2017042706/cpanreporter_001_config.ini
    PERL_EXTUTILS_AUTOINSTALL = --defaultdeps
    PERL_PARALLEL_SMOKER = yes
    PERL_USE_UNSAFE_INC = 1
    SHELL = /bin/zsh
    TERM = screen
    TMPDIR = /var/tmp/cpansmoker-1023/2017042706

Perl special variables (and OS-specific diagnostics, for MSWin32):

    $^X = /bbbike/perl-5.25.12t/bin/perl5.25.12
    $UID/$EUID = 1023 / 1023
    $GID = 1023 1023
    $EGID = 1023 1023

Perl module toolchain versions installed:

    Module              Have      
    ------------------- ----------
    CPAN                2.18      
    CPAN::Meta          2.150010  
    Cwd                 3.67      
    ExtUtils::CBuilder  0.280225  
    ExtUtils::Command   7.24      
    ExtUtils::Install   2.04      
    ExtUtils::MakeMaker 7.24      
    ExtUtils::Manifest  1.70      
    ExtUtils::ParseXS   3.34      
    File::Spec          3.67      
    JSON                2.90      
    JSON::PP            2.27400_02
    Module::Build       0.4222    
    Module::Signature   0.81      
    Parse::CPAN::Meta   2.150010  
    Test::Harness       3.39      
    Test::More          1.302083  
    YAML                1.23      
    YAML::Syck          1.30      
    version             0.9918    


--

Summary of my perl5 (revision 5 version 25 subversion 12) configuration:
   
  Platform:
    osname=linux
    osvers=3.16.0-4-amd64
    archname=x86_64-linux-thread-multi
    uname='linux eserte 3.16.0-4-amd64 #1 smp debian 3.16.39-1+deb8u2 (2017-03-07) x86_64 gnulinux '
    config_args='-ds -e -Dprefix=/opt/perl-5.25.12t -Dusedevel -Dusemallocwrap=no -Dusethreads -Dcf_email=srezic@cpan.org'
    hint=recommended
    useposix=true
    d_sigaction=define
    useithreads=define
    usemultiplicity=define
    use64bitint=define
    use64bitall=define
    uselongdouble=undef
    usemymalloc=n
    default_inc_excludes_dot=define
    bincompat5005=undef
  Compiler:
    cc='cc'
    ccflags ='-D_REENTRANT -D_GNU_SOURCE -fwrapv -fno-strict-aliasing -pipe -fstack-protector-strong -I/usr/local/include -D_LARGEFILE_SOURCE -D_FILE_OFFSET_BITS=64 -D_FORTIFY_SOURCE=2'
    optimize='-O2'
    cppflags='-D_REENTRANT -D_GNU_SOURCE -fwrapv -fno-strict-aliasing -pipe -fstack-protector-strong -I/usr/local/include'
    ccversion=''
    gccversion='4.9.2'
    gccosandvers=''
    intsize=4
    longsize=8
    ptrsize=8
    doublesize=8
    byteorder=12345678
    doublekind=3
    d_longlong=define
    longlongsize=8
    d_longdbl=define
    longdblsize=16
    longdblkind=3
    ivtype='long'
    ivsize=8
    nvtype='double'
    nvsize=8
    Off_t='off_t'
    lseeksize=8
    alignbytes=8
    prototype=define
  Linker and Libraries:
    ld='cc'
    ldflags =' -fstack-protector-strong -L/usr/local/lib'
    libpth=/usr/local/lib /usr/lib/gcc/x86_64-linux-gnu/4.9/include-fixed /usr/include/x86_64-linux-gnu /usr/lib /lib/x86_64-linux-gnu /lib/../lib /usr/lib/x86_64-linux-gnu /usr/lib/../lib /lib
    libs=-lpthread -lnsl -lgdbm -ldb -ldl -lm -lcrypt -lutil -lc -lgdbm_compat
    perllibs=-lpthread -lnsl -ldl -lm -lcrypt -lutil -lc
    libc=libc-2.19.so
    so=so
    useshrplib=false
    libperl=libperl.a
    gnulibc_version='2.19'
  Dynamic Linking:
    dlsrc=dl_dlopen.xs
    dlext=so
    d_dlsymun=undef
    ccdlflags='-Wl,-E'
    cccdlflags='-fPIC'
    lddlflags='-shared -O2 -L/usr/local/lib -fstack-protector-strong'


Characteristics of this binary (from libperl): 
  Compile-time options:
    HAS_TIMES
    MULTIPLICITY
    PERLIO_LAYERS
    PERL_COPY_ON_WRITE
    PERL_DONT_CREATE_GVSV
    PERL_IMPLICIT_CONTEXT
    PERL_OP_PARENT
    PERL_PRESERVE_IVUV
    PERL_USE_DEVEL
    USE_64_BIT_ALL
    USE_64_BIT_INT
    USE_ITHREADS
    USE_LARGE_FILES
    USE_LOCALE
    USE_LOCALE_COLLATE
    USE_LOCALE_CTYPE
    USE_LOCALE_NUMERIC
    USE_LOCALE_TIME
    USE_PERLIO
    USE_PERL_ATOF
    USE_REENTRANT_API
  Built under linux
  Compiled at Apr 20 2017 20:18:03
  %ENV:
    PERL5LIB=""
    PERL5OPT=""
    PERL5_CPANPLUS_IS_RUNNING="29543"
    PERL5_CPAN_IS_RUNNING="29543"
    PERL5_CPAN_IS_RUNNING_IN_RECURSION="4632,29543"
    PERLDOC="-MPod::Perldoc::ToTextOverstrike"
    PERL_BATCH="yes"
    PERL_CANARY_STABILITY_NOPROMPT="1"
    PERL_CPAN_REPORTER_CONFIG="/var/tmp/cpansmoker-1023/2017042706/cpanreporter_001_config.ini"
    PERL_EXTUTILS_AUTOINSTALL="--defaultdeps"
    PERL_PARALLEL_SMOKER="yes"
    PERL_USE_UNSAFE_INC="1"
  @INC:
    /opt/perl-5.25.12t/lib/site_perl/5.25.12/x86_64-linux-thread-multi
    /opt/perl-5.25.12t/lib/site_perl/5.25.12
    /opt/perl-5.25.12t/lib/5.25.12/x86_64-linux-thread-multi
    /opt/perl-5.25.12t/lib/5.25.12
    .