service check timed out on upgraded Debian stretch

[PetrMa]

Hello,
today i upgraded my Debian server with Nagios Core to stretch version. Everything works fine except check_vmware_api plugin.
All checks ends with status "service check timed out after 60.01 seconds".

Can you help me please? I havn´t any idea whats wrong :( I have installed Nagios 4.3.2 with vsphere cli 6.5.0 (this version works fine on previous version of Debian).

Thanks, petr

[fmikker]

Hi Petr.

This might be related to the issue reported here: https://jira.op5.com/browse/MON-10348

[PetrMa]

I found old comments on https://exchange.nagios.org/directory/Plugins/Operating-Systems/%2A-Virtual-Environments/VMWare/check_vmware_api/details which describes a solution with installation of older version of GAAS/Net-HTTP-6.03.tar.gz but this plugin is not accessible anymore and I can´t to apply this solution.

[PetrMa]

New very interesting point. I made complete rolback by vmware snapshot back to old Debian, then I upgraded to new Debian Stretch, install all perl plugins, install vmware vsphere cli and plugin works fine. But after few second the all commands again ends with error "CHECK_VMWARE_API.PL UNKNOWN - plugin timed out (timeout 30s)".

What´s wrong? Why the plugin work only few second and after that not?
Any suggest please?
Restart of Nagios does not help, reinstallation of vmware cli also.

root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm90 -u user -p password -l cpu -w 90 -c 95
CHECK_VMWARE_API.PL OK - cpu usage=9743.00 MHz (23.42%) | cpu_usagemhz=9743.00;90;95 cpu_usage=23.42%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm90 -u user -p password -l cpu -w 90 -c 95
CHECK_VMWARE_API.PL OK - cpu usage=9743.00 MHz (23.42%) | cpu_usagemhz=9743.00;90;95 cpu_usage=23.42%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm90 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL OK - mem usage=70.32 % | mem_usage=70.32%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm91 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL OK - mem usage=70.31 % | mem_usage=70.31%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm96 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL OK - mem usage=55.60 % | mem_usage=55.60%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm90 -u user -p password -l cpu -w 90 -c 95
CHECK_VMWARE_API.PL OK - cpu usage=7006.00 MHz (16.84%) | cpu_usagemhz=7006.00;90;95 cpu_usage=16.84%;90;95
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm90 -u user -p password -l cpu -w 90 -c 95
CHECK_VMWARE_API.PL UNKNOWN - plugin timed out (timeout 30s)
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm96 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL UNKNOWN - plugin timed out (timeout 30s)
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm96 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL UNKNOWN - plugin timed out (timeout 30s)
root@server:/usr/local/user/libexec# ./check_vmware_api.pl -H czprgvm91 -u user -p password -l mem -s usage -w 90 -c 95
CHECK_VMWARE_API.PL UNKNOWN - plugin timed out (timeout 30s)

[pierluigiconti]

that's the same issue I'm having:
#15

[PetrMa]

I changed the vmware plugin to check_vmware_esx.pl and everything works fine without issues. For me this is good solution now.

[nbrys]

I have the exact same problem on our ubuntu 16.04. I have the following cpan modules installed:

App::Prove 3.3500 3.3900 App::Prove::State 3.3500 3.3900 App::Prove::State::Result 3.3500 3.3900 App::Prove::State::Result::Test 3.3500 3.3900 Archive::Tar 2.0400 2.2600 Archive::Tar::Constant 2.0400 2.2600 Archive::Tar::File 2.0400 2.2600 Attribute::Handlers 0.9700 0.9900 B 1.5800 1.6800 B::Concise 0.9960 0.9990 B::Debug 1.2300 1.2500 B::Deparse 1.3500 1.4000 B::Op_private 5.0220 5.0260 B::Terse 1.0600 1.0700 B::Xref 1.0500 1.0600 Benchmark 1.2000 1.2200 Bytes::Random::Secure 0.2800 0.2900 CGI 4.2600 4.3600 CGI::Carp 4.2600 4.3600 CGI::Cookie 4.2600 4.3600 CGI::Fast 2.1000 2.1200 CGI::File::Temp 4.2600 4.3600 CGI::Pretty 4.2600 4.3600 CGI::Push 4.2600 4.3600 CGI::Util 4.2600 4.3600 CPAN::Meta 2.1500 2.1500 CPAN::Meta::Converter 2.1500 2.1500 CPAN::Meta::Feature 2.1500 2.1500 CPAN::Meta::History 2.1500 2.1500 CPAN::Meta::Merge 2.1500 2.1500 CPAN::Meta::Prereqs 2.1500 2.1500 CPAN::Meta::Requirements 2.1320 2.1400 CPAN::Meta::Spec 2.1500 2.1500 CPAN::Meta::Validator 2.1500 2.1500 CPAN::Meta::YAML 0.0120 0.0180 Carp 1.3600 1.3800 Carp::Heavy 1.3600 1.3800 Compress::Raw::Bzip2 2.0680 2.0740 Compress::Raw::Zlib 2.0680 2.0740 Compress::Zlib 2.0680 2.0740 Config::Perl::V 0.2400 0.2700 Cwd 3.5600 3.6200 DBD::mysql 4.0330 4.0430 DBI 1.6340 1.6370 DB_File 1.8350 1.8400 Data::Dumper 2.1580 2.1610 Devel::PPPort 3.3100 3.3600 Devel::Peek 1.2200 1.2600 Digest::MD5 2.5400 2.5500 Digest::SHA 5.9500 5.9700 Encode 2.7200 2.9200 Encode::Alias 2.1900 2.2300 Encode::CN::HZ 2.0700 2.0900 Encode::Encoding 2.0700 2.0800 Encode::GSM0338 2.0500 2.0700 Encode::Guess 2.0600 2.0700 Encode::JP::JIS7 2.0500 2.0700 Encode::KR::2022_KR 2.0300 2.0400 Encode::MIME::Header 2.1600 2.2700 Encode::MIME::Header::ISO_2022_JP 1.0400 1.0900 Encode::MIME::Name 1.0100 1.0200 Encode::Unicode 2.0900 2.1600 Encode::Unicode::UTF7 2.0800 2.1000 English 1.0900 1.1000 Error 0.1701 0.1703 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. Error::Simple 0.0000 0.1703 ExtUtils::CBuilder 0.2802 0.2802 ExtUtils::CBuilder::Base 0.2802 0.2802 ExtUtils::CBuilder::Platform::Unix 0.2802 0.2802 ExtUtils::CBuilder::Platform::VMS 0.2802 0.2802 ExtUtils::CBuilder::Platform::Windows 0.2802 0.2802 ExtUtils::CBuilder::Platform::Windows::BCC 0.2802 0.2802 ExtUtils::CBuilder::Platform::Windows::GCC 0.2802 0.2802 ExtUtils::CBuilder::Platform::Windows::MSVC 0.2802 0.2802 ExtUtils::CBuilder::Platform::aix 0.2802 0.2802 ExtUtils::CBuilder::Platform::android 0.2802 0.2802 ExtUtils::CBuilder::Platform::cygwin 0.2802 0.2802 ExtUtils::CBuilder::Platform::darwin 0.2802 0.2802 ExtUtils::CBuilder::Platform::dec_osf 0.2802 0.2802 ExtUtils::CBuilder::Platform::os2 0.2802 0.2802 ExtUtils::Command 1.2000 7.3000 ExtUtils::Command::MM 6.9600 7.3000 ExtUtils::Constant 0.2300 0.2400 ExtUtils::Constant::Base 0.0500 0.0600 ExtUtils::Constant::ProxySubs 0.0800 0.0900 ExtUtils::Constant::Utils 0.0300 0.0400 ExtUtils::Embed 1.3200 1.3400 ExtUtils::Install 2.0400 2.1400 ExtUtils::Installed 2.0400 2.1400 ExtUtils::Liblist 6.9600 7.3000 ExtUtils::Liblist::Kid 6.9600 7.3000 ExtUtils::MM 6.9600 7.3000 ExtUtils::MM_AIX 6.9600 7.3000 ExtUtils::MM_Any 6.9600 7.3000 ExtUtils::MM_BeOS 6.9600 7.3000 ExtUtils::MM_Cygwin 6.9600 7.3000 ExtUtils::MM_DOS 6.9600 7.3000 ExtUtils::MM_Darwin 6.9600 7.3000 ExtUtils::MM_MacOS 6.9600 7.3000 ExtUtils::MM_NW5 6.9600 7.3000 ExtUtils::MM_OS2 6.9600 7.3000 ExtUtils::MM_QNX 6.9600 7.3000 ExtUtils::MM_UWIN 6.9600 7.3000 ExtUtils::MM_Unix 6.9600 7.3000 ExtUtils::MM_VMS 6.9600 7.3000 ExtUtils::MM_VOS 6.9600 7.3000 ExtUtils::MM_Win32 6.9600 7.3000 ExtUtils::MM_Win95 6.9600 7.3000 ExtUtils::MY 6.9600 7.3000 ExtUtils::MakeMaker 6.9600 7.3000 ExtUtils::MakeMaker::Config 6.9600 7.3000 Argument "7.04_01" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. ExtUtils::MakeMaker::Locale 7.0400 7.3000 Argument "7.04_01" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. ExtUtils::MakeMaker::version 7.0400 7.3000 Argument "7.04_01" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. ExtUtils::MakeMaker::version::regex 7.0400 7.3000 Argument "7.04_01" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. ExtUtils::MakeMaker::version::vpp 7.0400 7.3000 ExtUtils::Miniperl 1.0500 1.0600 ExtUtils::Mkbootstrap 6.9600 7.3000 ExtUtils::Mksymlists 6.9600 7.3000 ExtUtils::Packlist 2.0400 2.1400 ExtUtils::ParseXS 3.2800 3.3500 ExtUtils::ParseXS::Constants 3.2800 3.3500 ExtUtils::ParseXS::CountLines 3.2800 3.3500 ExtUtils::ParseXS::Eval 3.2800 3.3500 ExtUtils::ParseXS::Utilities 3.2800 3.3500 ExtUtils::Typemaps 3.2800 3.3500 ExtUtils::Typemaps::Cmd 3.2800 3.3500 ExtUtils::Typemaps::InputMap 3.2800 3.3500 ExtUtils::Typemaps::OutputMap 3.2800 3.3500 ExtUtils::Typemaps::Type 3.2800 3.3500 ExtUtils::testlib 6.9600 7.3000 FCGI 0.7700 0.7800 Fatal 2.2600 2.2900 Fh 4.2600 4.3600 File::Copy 2.3000 2.3200 File::Fetch 0.4800 0.5400 File::Find 1.2900 1.3400 File::Glob 1.2400 1.2800 File::Listing 5.8370 6.0400 File::Path 2.0900 2.1500 File::Spec 3.5600 3.6200 File::Spec::Cygwin 3.5600 3.6200 File::Spec::Epoc 3.5600 3.6200 File::Spec::Functions 3.5600 3.6200 File::Spec::Mac 3.5600 3.6200 File::Spec::OS2 3.5600 3.6200 File::Spec::Unix 3.5600 3.6200 File::Spec::VMS 3.5600 3.6200 File::Spec::Win32 3.5600 3.6200 FileHandle 2.0200 2.0300 Filter::Simple 0.9200 0.9400 Filter::Util::Call 1.5400 1.5700 Getopt::Long 2.4500 2.5000 Getopt::Std 1.1100 1.1200 Git 0.0100 0.4100 HTML::AsSubs 5.0300 5.0700 HTML::Element 5.0300 5.0700 HTML::Element::traverse 5.0300 5.0700 HTML::Form 5.8290 6.0300 HTML::FormatMarkdown 2.1100 2.1600 HTML::FormatPS 2.1100 2.1600 HTML::FormatRTF 2.1100 2.1600 HTML::FormatText 2.1100 2.1600 HTML::Formatter 2.1100 2.1600 HTML::Parse 5.0300 5.0700 HTML::Template 2.9500 2.9700 HTML::Tree 5.0300 5.0700 HTML::TreeBuilder 5.0300 5.0700 HTTP::Config 5.8350 6.1300 HTTP::Cookies 5.8370 6.0400 HTTP::Cookies::Microsoft 5.8210 6.0400 HTTP::Cookies::Netscape 5.8320 6.0400 HTTP::Daemon 5.8270 6.0100 HTTP::Date 5.8310 6.0200 HTTP::Headers 5.8350 6.1300 HTTP::Headers::Auth 5.8170 6.1300 HTTP::Headers::ETag 5.8100 6.1300 HTTP::Headers::Util 5.8170 6.1300 HTTP::Message 5.8370 6.1300 HTTP::Negotiate 5.8350 6.0100 HTTP::Request 5.8270 6.1300 HTTP::Request::Common 5.8240 6.1300 HTTP::Response 5.8360 6.1300 HTTP::Status 5.8170 6.1300 HTTP::Tiny 0.0540 0.0700 Hash::Util 0.1800 0.2200 Hash::Util::FieldHash 1.1500 1.1900 I18N::LangTags 0.4000 0.4200 I18N::LangTags::Detect 1.0500 1.0600 I18N::Langinfo 0.1200 0.1300 IO::Compress::Adapter::Bzip2 2.0680 2.0740 IO::Compress::Adapter::Deflate 2.0680 2.0740 IO::Compress::Adapter::Identity 2.0680 2.0740 IO::Compress::Base 2.0680 2.0740 IO::Compress::Base::Common 2.0680 2.0740 IO::Compress::Bzip2 2.0680 2.0740 IO::Compress::Deflate 2.0680 2.0740 IO::Compress::Gzip 2.0680 2.0740 IO::Compress::Gzip::Constants 2.0680 2.0740 IO::Compress::RawDeflate 2.0680 2.0740 IO::Compress::Zip 2.0680 2.0740 IO::Compress::Zip::Constants 2.0680 2.0740 IO::Compress::Zlib::Constants 2.0680 2.0740 IO::Compress::Zlib::Extra 2.0680 2.0740 IO::Socket::IP 0.3700 0.3900 IO::Uncompress::Adapter::Bunzip2 2.0680 2.0740 IO::Uncompress::Adapter::Identity 2.0680 2.0740 IO::Uncompress::Adapter::Inflate 2.0680 2.0740 IO::Uncompress::AnyInflate 2.0680 2.0740 IO::Uncompress::AnyUncompress 2.0680 2.0740 IO::Uncompress::Base 2.0680 2.0740 IO::Uncompress::Bunzip2 2.0680 2.0740 IO::Uncompress::Gunzip 2.0680 2.0740 IO::Uncompress::Inflate 2.0680 2.0740 IO::Uncompress::RawInflate 2.0680 2.0740 IO::Uncompress::Unzip 2.0680 2.0740 IPC::Cmd 0.9200 0.9800 IPC::Msg 2.0400 2.0700 IPC::Open3 1.1800 1.2000 IPC::Semaphore 2.0400 2.0700 IPC::SharedMem 2.0400 2.0700 IPC::SysV 2.0400 2.0700 JSON::PP 2.2730 2.9400 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. JSON::PP::Boolean 0.0000 2.9400 LWP 5.8370 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Authen::Basic 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Authen::Digest 0.0000 6.2700 LWP::Authen::Ntlm 5.8350 6.2700 LWP::ConnCache 5.8100 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Debug 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::DebugFile 0.0000 6.2700 LWP::MediaTypes 5.8350 6.0200 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::MemberMixin 0.0000 6.2700 LWP::Protocol 5.8290 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::GHTTP 0.0000 6.1700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::cpan 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::data 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::file 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::ftp 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::gopher 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::http 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::http10 0.0000 6.0300 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::https 0.0000 6.0700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::loopback 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::mailto 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::nntp 0.0000 6.2700 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. LWP::Protocol::nogo 0.0000 6.2700 LWP::RobotUA 5.8350 6.2700 LWP::Simple 5.8350 6.2700 LWP::UserAgent 5.8350 6.2700 Locale::Codes 3.3400 3.5300 Locale::Codes::Constants 3.3400 3.5300 Locale::Codes::Country 3.3400 3.5300 Locale::Codes::Country_Codes 3.3400 3.5400 Locale::Codes::Country_Retired 3.3400 3.5400 Locale::Codes::Currency 3.3400 3.5400 Locale::Codes::Currency_Codes 3.3400 3.5400 Locale::Codes::Currency_Retired 3.3400 3.5400 Locale::Codes::LangExt 3.3400 3.5400 Locale::Codes::LangExt_Codes 3.3400 3.5400 Locale::Codes::LangExt_Retired 3.3400 3.5400 Locale::Codes::LangFam 3.3400 3.5400 Locale::Codes::LangFam_Codes 3.3400 3.5400 Locale::Codes::LangFam_Retired 3.3400 3.5400 Locale::Codes::LangVar 3.3400 3.5400 Locale::Codes::LangVar_Codes 3.3400 3.5400 Locale::Codes::LangVar_Retired 3.3400 3.5400 Locale::Codes::Language 3.3400 3.5400 Locale::Codes::Language_Codes 3.3400 3.5400 Locale::Codes::Language_Retired 3.3400 3.5400 Locale::Codes::Script 3.3400 3.5400 Locale::Codes::Script_Codes 3.3400 3.5400 Locale::Codes::Script_Retired 3.3400 3.5400 Locale::Country 3.3400 3.5400 Locale::Currency 3.3400 3.5400 Locale::Language 3.3400 3.5400 Locale::Maketext 1.2600 1.2800 Locale::Script 3.3400 3.5400 Mail::Address 2.1300 2.1900 Mail::Cap 2.1300 2.1900 Mail::Field 2.1300 2.1900 Mail::Field::AddrList 2.1300 2.1900 Mail::Field::Date 2.1300 2.1900 Mail::Field::Generic 2.1300 2.1900 Mail::Filter 2.1300 2.1900 Mail::Header 2.1300 2.1900 Mail::Internet 2.1300 2.1900 Mail::Mailer 2.1300 2.1900 Mail::Mailer::qmail 2.1300 2.1900 Mail::Mailer::rfc822 2.1300 2.1900 Mail::Mailer::sendmail 2.1300 2.1900 Mail::Mailer::smtp 2.1300 2.1900 Mail::Mailer::smtps 2.1300 2.1900 Mail::Mailer::testfile 2.1300 2.1900 Mail::Send 2.1300 2.1900 Mail::Util 2.1300 2.1900 Math::BigFloat 1.9997 1.9998 Math::BigFloat::Trace 0.3600 0.4700 Math::BigInt 1.9997 1.9998 Math::BigInt::Calc 1.9997 1.9998 Math::BigInt::CalcEmu 1.9997 1.9998 Math::BigInt::FastCalc 0.3100 0.5006 Math::BigInt::Trace 0.3600 0.4700 Math::BigRat 0.2608 0.2613 Math::Random::ISAAC 1.0030 1.0040 Math::Random::ISAAC::PP 1.0030 1.0040 Module::Build 0.4205 0.4224 Module::Build::Base 0.4205 0.4224 Module::Build::Compat 0.4205 0.4224 Module::Build::Config 0.4205 0.4224 Module::Build::Cookbook 0.4205 0.4224 Module::Build::Dumper 0.4205 0.4224 Module::Build::ModuleInfo 0.4205 0.4210 Module::Build::Notes 0.4205 0.4224 Module::Build::PPMMaker 0.4205 0.4224 Module::Build::Platform::Default 0.4205 0.4224 Module::Build::Platform::MacOS 0.4205 0.4224 Module::Build::Platform::Unix 0.4205 0.4224 Module::Build::Platform::VMS 0.4205 0.4224 Module::Build::Platform::VOS 0.4205 0.4224 Module::Build::Platform::Windows 0.4205 0.4224 Module::Build::Platform::aix 0.4205 0.4224 Module::Build::Platform::cygwin 0.4205 0.4224 Module::Build::Platform::darwin 0.4205 0.4224 Module::Build::Platform::os2 0.4205 0.4224 Module::Build::PodParser 0.4205 0.4224 Module::CoreList 5.2015 5.2017 Module::CoreList::TieHashDelta 5.2015 5.2017 Module::CoreList::Utils 5.2015 5.2017 Module::Load::Conditional 0.6400 0.6800 Module::Metadata 1.0000 1.0000 NEXT 0.6500 0.6700 Net::Cmd 2.2900 3.1000 Net::Config 1.1100 3.1000 Net::Domain 2.2000 3.1000 Net::FTP 2.7700 3.1000 Net::FTP::A 1.1800 3.1000 Net::FTP::E 0.0100 3.1000 Net::FTP::I 1.1200 3.1000 Net::FTP::L 0.0100 3.1000 Net::FTP::dataconn 0.1100 3.1000 Net::HTTP 5.8340 6.1700 Net::HTTP::Methods 5.8340 6.1700 Net::HTTP::NB 5.8100 6.1700 Net::HTTPS 5.8190 6.1700 Net::NNTP 2.2400 3.1000 Net::Netrc 2.1200 3.1000 Net::POP3 2.2900 3.1000 Net::Ping 2.4300 2.6100 Net::SMTP 2.3100 3.1000 Net::SMTP::SSL 1.0300 1.0400 Net::Time 2.1000 3.1000 ODBM_File 1.1200 1.1400 Opcode 1.3200 1.3900 Argument "1.53_01" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. POSIX 1.5300 1.7600 Parse::CPAN::Meta 1.4414 2.1500 Perl::OSType 1.0080 1.0100 PerlIO 1.0900 1.1000 PerlIO::encoding 0.2100 0.2500 PerlIO::mmap 0.0140 0.0160 PerlIO::scalar 0.2300 0.2600 PerlIO::via 0.1500 0.1600 Pod::Checker 1.6000 1.7300 Pod::Html 1.2200 1.2202 Pod::Man 2.2800 4.0900 Pod::ParseLink 1.1000 4.0900 Pod::Perldoc 3.2500 3.2800 Pod::Perldoc::BaseTo 3.2500 3.2800 Pod::Perldoc::GetOptsOO 3.2500 3.2800 Pod::Perldoc::ToANSI 3.2500 3.2800 Pod::Perldoc::ToChecker 3.2500 3.2800 Pod::Perldoc::ToMan 3.2500 3.2800 Pod::Perldoc::ToNroff 3.2500 3.2800 Pod::Perldoc::ToPod 3.2500 3.2800 Pod::Perldoc::ToRtf 3.2500 3.2800 Pod::Perldoc::ToTerm 3.2500 3.2800 Pod::Perldoc::ToText 3.2500 3.2800 Pod::Perldoc::ToTk 3.2500 3.2800 Pod::Perldoc::ToXml 3.2500 3.2800 Pod::Simple 3.2900 3.3500 Pod::Simple::BlackBox 3.2900 3.3500 Pod::Simple::Checker 3.2900 3.3500 Pod::Simple::Debug 3.2900 3.3500 Pod::Simple::DumpAsText 3.2900 3.3500 Pod::Simple::DumpAsXML 3.2900 3.3500 Pod::Simple::HTML 3.2900 3.3500 Pod::Simple::HTMLBatch 3.2900 3.3500 Pod::Simple::LinkSection 3.2900 3.3500 Pod::Simple::Methody 3.2900 3.3500 Pod::Simple::Progress 3.2900 3.3500 Pod::Simple::PullParser 3.2900 3.3500 Pod::Simple::PullParserEndToken 3.2900 3.3500 Pod::Simple::PullParserStartToken 3.2900 3.3500 Pod::Simple::PullParserTextToken 3.2900 3.3500 Pod::Simple::PullParserToken 3.2900 3.3500 Pod::Simple::RTF 3.2900 3.3500 Pod::Simple::Search 3.2900 3.3500 Pod::Simple::SimpleTree 3.2900 3.3500 Pod::Simple::Text 3.2900 3.3500 Pod::Simple::TextContent 3.2900 3.3500 Pod::Simple::TiedOutFH 3.2900 3.3500 Pod::Simple::Transcode 3.2900 3.3500 Pod::Simple::TranscodeDumb 3.2900 3.3500 Pod::Simple::TranscodeSmart 3.2900 3.3500 Pod::Simple::XHTML 3.2900 3.3500 Pod::Simple::XMLOutStream 3.2900 3.3500 Pod::Text 3.1800 4.0900 Pod::Text::Color 2.0700 4.0900 Pod::Text::Overstrike 2.0500 4.0900 Pod::Text::Termcap 2.0800 4.0900 Pod::Usage 1.6400 1.6900 SDBM_File 1.1300 1.1400 SelfLoader 1.2200 1.2400 Socket 2.0180 2.0240 Symbol 1.0700 1.0800 Sys::Syslog 0.3300 0.3500 TAP::Base 3.3500 3.3900 TAP::Formatter::Base 3.3500 3.3900 TAP::Formatter::Color 3.3500 3.3900 TAP::Formatter::Console 3.3500 3.3900 TAP::Formatter::Console::ParallelSession 3.3500 3.3900 TAP::Formatter::Console::Session 3.3500 3.3900 TAP::Formatter::File 3.3500 3.3900 TAP::Formatter::File::Session 3.3500 3.3900 TAP::Formatter::Session 3.3500 3.3900 TAP::Harness 3.3500 3.3900 TAP::Harness::Env 3.3500 3.3900 TAP::Object 3.3500 3.3900 TAP::Parser 3.3500 3.3900 TAP::Parser::Aggregator 3.3500 3.3900 TAP::Parser::Grammar 3.3500 3.3900 TAP::Parser::Iterator 3.3500 3.3900 TAP::Parser::Iterator::Array 3.3500 3.3900 TAP::Parser::Iterator::Process 3.3500 3.3900 TAP::Parser::Iterator::Stream 3.3500 3.3900 TAP::Parser::IteratorFactory 3.3500 3.3900 TAP::Parser::Multiplexer 3.3500 3.3900 TAP::Parser::Result 3.3500 3.3900 TAP::Parser::Result::Bailout 3.3500 3.3900 TAP::Parser::Result::Comment 3.3500 3.3900 TAP::Parser::Result::Plan 3.3500 3.3900 TAP::Parser::Result::Pragma 3.3500 3.3900 TAP::Parser::Result::Test 3.3500 3.3900 TAP::Parser::Result::Unknown 3.3500 3.3900 TAP::Parser::Result::Version 3.3500 3.3900 TAP::Parser::Result::YAML 3.3500 3.3900 TAP::Parser::ResultFactory 3.3500 3.3900 TAP::Parser::Scheduler 3.3500 3.3900 TAP::Parser::Scheduler::Job 3.3500 3.3900 TAP::Parser::Scheduler::Spinner 3.3500 3.3900 TAP::Parser::Source 3.3500 3.3900 TAP::Parser::SourceHandler 3.3500 3.3900 TAP::Parser::SourceHandler::Executable 3.3500 3.3900 TAP::Parser::SourceHandler::File 3.3500 3.3900 TAP::Parser::SourceHandler::Handle 3.3500 3.3900 TAP::Parser::SourceHandler::Perl 3.3500 3.3900 TAP::Parser::SourceHandler::RawTAP 3.3500 3.3900 TAP::Parser::YAMLish::Reader 3.3500 3.3900 TAP::Parser::YAMLish::Writer 3.3500 3.3900 Term::ANSIColor 4.0300 4.0600 Term::Cap 1.1500 1.1700 Test::Harness 3.3500 3.3900 Thread::Queue 3.0500 3.1200 Thread::Semaphore 2.1200 2.1300 Tie::Hash::NamedCapture 0.0900 0.1000 Tie::Scalar 1.0300 1.0400 Time::HiRes 1.9726 1.9746 Time::Local 1.2300 1.2500 Time::Piece 1.2900 1.3202 Time::Seconds 1.2900 1.3202 UNIVERSAL 1.1200 1.1300 URI 1.7100 1.7200 URI::IRI 1.7100 1.7200 URI::QueryParam 1.7100 1.7200 URI::Split 1.7100 1.7200 URI::data 1.7100 1.7200 URI::file::Base 1.7100 1.7200 URI::file::FAT 1.7100 1.7200 URI::file::Mac 1.7100 1.7200 URI::file::OS2 1.7100 1.7200 URI::file::QNX 1.7100 1.7200 URI::file::Unix 1.7100 1.7200 URI::file::Win32 1.7100 1.7200 URI::ftp 1.7100 1.7200 URI::gopher 1.7100 1.7200 URI::http 1.7100 1.7200 URI::https 1.7100 1.7200 URI::ldap 1.7100 1.7200 URI::ldapi 1.7100 1.7200 URI::ldaps 1.7100 1.7200 URI::mailto 1.7100 1.7200 URI::mms 1.7100 1.7200 URI::news 1.7100 1.7200 URI::nntp 1.7100 1.7200 URI::pop 1.7100 1.7200 URI::rlogin 1.7100 1.7200 URI::rsync 1.7100 1.7200 URI::rtsp 1.7100 1.7200 URI::rtspu 1.7100 1.7200 URI::sftp 1.7100 1.7200 URI::sip 1.7100 1.7200 URI::sips 1.7100 1.7200 URI::snews 1.7100 1.7200 URI::ssh 1.7100 1.7200 URI::telnet 1.7100 1.7200 URI::tn3270 1.7100 1.7200 URI::urn 1.7100 1.7200 Argument "undef" isn't numeric in printf at /usr/local/share/perl/5.22.1/App/Cpan.pm line 1353. URI::urn::isbn 0.0000 1.7200 URI::urn::oid 1.7100 1.7200 Unicode::Collate 1.1200 1.1900 Unicode::Collate::CJK::Big5 1.1200 1.1900 Unicode::Collate::CJK::GB2312 1.1200 1.1900 Unicode::Collate::CJK::JISX0208 1.1200 1.1900 Unicode::Collate::CJK::Korean 1.1200 1.1900 Unicode::Collate::CJK::Pinyin 1.1200 1.1900 Unicode::Collate::CJK::Stroke 1.1200 1.1900 Unicode::Collate::CJK::Zhuyin 1.1200 1.1900 Unicode::Collate::Locale 1.1200 1.1900 Unicode::Normalize 1.1800 1.2500 Unicode::UCD 0.6100 0.6800 WWW::RobotRules 5.8320 6.0200 WWW::RobotRules::AnyDBM_File 5.8350 6.0000 XML::LibXML 2.0123 2.0129 XML::LibXML::AttributeHash 2.0123 2.0129 XML::LibXML::Boolean 2.0123 2.0129 XML::LibXML::Common 2.0123 2.0129 XML::LibXML::Devel 2.0123 2.0129 XML::LibXML::ErrNo 2.0123 2.0129 XML::LibXML::Error 2.0123 2.0129 XML::LibXML::Literal 2.0123 2.0129 XML::LibXML::NodeList 2.0123 2.0129 XML::LibXML::Number 2.0123 2.0129 XML::LibXML::Reader 2.0123 2.0129 XML::LibXML::SAX 2.0123 2.0129 XML::LibXML::SAX::Builder 2.0123 2.0129 XML::LibXML::SAX::Generator 2.0123 2.0129 XML::LibXML::SAX::Parser 2.0123 2.0129 XML::LibXML::XPathContext 2.0123 2.0129 XML::NamespaceSupport 1.1100 1.1200 XML::SAX::Base 1.0700 1.0900 XML::SAX::Exception 1.0700 1.0900 XML::SAX::Expat 0.4000 0.5100 XML::SemanticDiff 1.0005 1.0006 XML::SemanticDiff::BasicHandler 1.0005 1.0006 XSLoader 0.2000 0.2400 arybase 0.1000 0.1200 attributes 0.2700 0.2900 autodie 2.2600 2.2900 autodie::Scope::Guard 2.2600 2.2900 autodie::Scope::GuardStack 2.2600 2.2900 autodie::exception 2.2600 2.2900 autodie::exception::system 2.2600 2.2900 autodie::hints 2.2600 2.2900 autodie::skip 2.2600 2.2900 autouse 1.0800 1.1100 base 2.2200 2.2300 bigint 0.3900 0.4700 bignum 0.3900 0.4700 bigrat 0.3900 0.4700 bytes 1.0400 1.0500 charnames 1.4300 1.4500 diagnostics 1.3400 1.3600 encoding 2.1400 2.2000 experimental 0.0130 0.0160 feature 1.4000 1.4700 fields 2.1700 2.2300 if 0.0604 0.0606 inc::latest 0.4205 0.5000 inc::latest::private 0.4205 0.5000 locale 1.0600 1.0900 mro 1.1700 1.2000 open 1.1000 1.1100 overload 1.2600 1.2800 parent 0.2320 0.2360 perlfaq 5.0210 5.0210 re 0.3200 0.3400 strict 1.0900 1.1100 threads 2.0100 2.1600 threads::shared 1.4800 1.5700 utf8 1.1700 1.1900 version 0.9909 0.9918 version::regex 0.9909 0.9918 version::vpp 0.9909 0.9918 warnings 1.3400 1.3700

[nbrys]

does someone has a clue on how to fix this?

[pvdputte]

When stracing the script I noticed that it was trying to read from /dev/random and was stuck there.

futex(0x7f9899c5cb70, FUTEX_WAKE_PRIVATE, 2147483647) = 0
stat("/var/run/egd-pool", 0x5582758e8298) = -1 ENOENT (No such file or directory)
stat("/dev/egd-pool", 0x5582758e8298)   = -1 ENOENT (No such file or directory)
stat("/etc/egd-pool", 0x5582758e8298)   = -1 ENOENT (No such file or directory)
stat("/etc/entropy", 0x5582758e8298)    = -1 ENOENT (No such file or directory)
stat("/dev/random", {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 8), ...}) = 0
geteuid()                               = 0
open("/dev/random", O_RDONLY)           = 3
ioctl(3, TCGETS, 0x7ffde3db9c00)        = -1 EINVAL (Invalid argument)
lseek(3, 0, SEEK_CUR)                   = 0
fstat(3, {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 8), ...}) = 0
fcntl(3, F_SETFD, FD_CLOEXEC)           = 0
read(3, "\261\37\23d\235%\343\341\366_\260\2024\305\341\275\252\312Q\26\27\231|\274", 32) = 24
read(3,

If there is not enough entropy available, it simply waits until more bits can be read from /dev/random (in other words, /dev/random is 'blocking', see https://unix.stackexchange.com/questions/243127/how-to-check-if-reading-from-dev-random-will-block)

If there are enough random bytes in /dev/random, everything works as it should.

# cat /proc/sys/kernel/random/entropy_avail
684

=> fast return of the script
If I run it again immediately, it blocks for a few seconds as it apparently needs about 380 bytes in my case:

# cat /proc/sys/kernel/random/entropy_avail
305
[... run script...]
[... allow script to complete while it waits for more entropy bits...]
# cat /proc/sys/kernel/random/entropy_avail
4

So then it is exhausted and the next call of the script will hang a long time and probably timeout. If the script is run in parallel, all instances are waiting for entropy and timeout.

As this is a problem on my new debian stretch VM and wasn't on the previous one (squeeze), I assume it must have something to do with updated Perl libs or SDK code which now get their entropy from /dev/random and maybe previously from /dev/urandom (which does not block because it provides pseudorandom bits if the real entropy is exhausted)

Anyway, installing 'haveged' (HArdware Volatile Entropy Gathering and Expansion Daemon) provides my /dev/random with more entropy and hence, fixed my problem. (note: not running in production yet)

I notice in the strace output that it's looking for other files first:

stat("/var/run/egd-pool", 0x5582758e8298) = -1 ENOENT (No such file or directory)
stat("/dev/egd-pool", 0x5582758e8298)   = -1 ENOENT (No such file or directory)
stat("/etc/egd-pool", 0x5582758e8298)   = -1 ENOENT (No such file or directory)
stat("/etc/entropy", 0x5582758e8298)    = -1 ENOENT (No such file or directory)
stat("/dev/random", {st_mode=S_IFCHR|0666, st_rdev=makedev(1, 8), ...}) = 0

So maybe there are other workarounds which provide the same result e.g. by providing a /dev/egd-pool device.

More information:
http://www.issihosts.com/haveged/
https://security.stackexchange.com/questions/34523/is-it-appropriate-to-use-haveged-as-a-source-of-entropy-on-virtual-machines

[jacobbaungard]

This plugin is "deprecated" from our side, and so most likely won't see any updates. We are therefore closing all open issues. Sorry about that.

The following project, which was forked from this one, might be of interest to you: https://github.com/BaldMansMojo/check_vmware_esx/