Uploaded image for project: 'ZABBIX BUGS AND ISSUES'
  1. ZABBIX BUGS AND ISSUES
  2. ZBX-20856

windows agents service errors with "pipe has ended" during termination

XMLWordPrintable

    • Icon: Incident report Incident report
    • Resolution: Unresolved
    • Icon: Minor Minor
    • None
    • 6.0.2
    • Agent (G)
    • None

      During service stop I periodically get errors "pipe has ended".

      It happens when stopping the service from services MCC or from task manager.

      In such cases there no "... stopped" messages written to native zabbix_agentd.log about termination. Only this:

       5904:20220405:221254.209 Starting Zabbix Agent [it0]. Zabbix 6.0.1 (revision a80cb13868).
        5904:20220405:221254.209 **** Enabled features ****
        5904:20220405:221254.209 IPv6 support:          YES
        5904:20220405:221254.209 TLS support:           YES
        5904:20220405:221254.209 **************************
        5904:20220405:221254.209 using configuration file: C:\zabbix\zabbix_agentd.conf
        5904:20220405:221255.680 agent #0 started [main process]
        5352:20220405:221255.680 agent #1 started [collector]
        6072:20220405:221255.680 agent #2 started [listener #1]
        3024:20220405:221255.695 agent #5 started [active checks #1]
        2412:20220405:221255.695 agent #3 started [listener #2]
        6608:20220405:221255.725 agent #4 started [listener #3]
        8676:20220405:221311.329 Starting Zabbix Agent [it0]. Zabbix 6.0.1 (revision a80cb13868).
        8676:20220405:221311.329 **** Enabled features ****
        8676:20220405:221311.329 IPv6 support:          YES
        8676:20220405:221311.329 TLS support:           YES
        8676:20220405:221311.329 **************************
        8676:20220405:221311.329 using configuration file: C:\zabbix\zabbix_agentd.conf
        8676:20220405:221311.329 In init_collector_data()
        8676:20220405:221311.329 logical CPU count 2
        8676:20220405:221311.329 End of init_collector_data()
        8676:20220405:221311.329 In init_perf_collector()
        8676:20220405:221311.329 In init_builtin_counter_indexes()
        8676:20220405:221311.329 In get_all_counter_names()
        8676:20220405:221311.353 End of get_all_counter_names()
        8676:20220405:221311.463 End of init_builtin_counter_indexes():SUCCEED
        8676:20220405:221311.463 In set_object_names()
        8676:20220405:221312.423 In get_all_counter_names()
        8676:20220405:221312.435 End of get_all_counter_names()
        8676:20220405:221312.435 In get_all_counter_names()
        8676:20220405:221312.460 End of get_all_counter_names()
        8676:20220405:221312.496 End of set_object_names():SUCCEED
        8676:20220405:221312.496 End of init_perf_collector():SUCCEED
        8676:20220405:221312.496 OpenSSL library (version OpenSSL 1.1.1k  25 Mar 2021) initialized
        8676:20220405:221312.496 agent #0 started [main process]
        9052:20220405:221312.496 agent #1 started [collector]
        8432:20220405:221312.496 agent #2 started [listener #1]
        9052:20220405:221312.496 In init_cpu_collector()
        8016:20220405:221312.496 agent #3 started [listener #2]
        8432:20220405:221312.496 In zbx_tls_init_child()
        9052:20220405:221312.496 In get_counter_name() pdhIndex:238
        8016:20220405:221312.496 In zbx_tls_init_child()
        3284:20220405:221312.496 agent #5 started [active checks #1]
        5316:20220405:221312.496 agent #4 started [listener #3]
        9052:20220405:221312.496 End of get_counter_name():SUCCEED
        8432:20220405:221312.496 End of zbx_tls_init_child()
        8016:20220405:221312.496 End of zbx_tls_init_child()
        3284:20220405:221312.496 In zbx_tls_init_child()
        5316:20220405:221312.496 In zbx_tls_init_child()
        9052:20220405:221312.496 In get_counter_name() pdhIndex:6
        3284:20220405:221312.496 End of zbx_tls_init_child()
        5316:20220405:221312.496 End of zbx_tls_init_child()
        9052:20220405:221312.496 End of get_counter_name():SUCCEED
        3284:20220405:221312.496 In init_active_metrics()
        9052:20220405:221312.508 In add_perf_counter() counter:'\Processor(_Total)\% Processor Time' interval:900
        3284:20220405:221312.508 buffer: first allocation for 100 elements
        3284:20220405:221312.508 End of init_active_metrics()
        3284:20220405:221312.508 In send_buffer() host:'10.33.0.4' port:10051 entries:0/100
        3284:20220405:221312.508 End of send_buffer():SUCCEED
        3284:20220405:221312.508 In refresh_active_checks() host:'10.33.0.4' port:10051
        3284:20220405:221312.508 In connect_to_server() [10.33.0.4]:10051 [timeout:3, connection timeout:3]
        3284:20220405:221312.508 In is_ip4() ip:'10.33.0.4'
        3284:20220405:221312.508 End of is_ip4():SUCCEED
        3284:20220405:221312.508 sending [{"request":"active checks","host":"it0"}]
        3284:20220405:221312.508 before read
        9052:20220405:221312.508 add_perf_counter(): PerfCounter '\Processor(_Total)\% Processor Time' successfully added
        9052:20220405:221312.508 End of add_perf_counter(): SUCCEED
        9052:20220405:221312.508 In add_perf_counter() counter:'\Processor(0)\% Processor Time' interval:900
        9052:20220405:221312.508 add_perf_counter(): PerfCounter '\Processor(0)\% Processor Time' successfully added
        9052:20220405:221312.508 End of add_perf_counter(): SUCCEED
        9052:20220405:221312.508 In add_perf_counter() counter:'\Processor(1)\% Processor Time' interval:900
        9052:20220405:221312.508 add_perf_counter(): PerfCounter '\Processor(1)\% Processor Time' successfully added
        9052:20220405:221312.508 End of add_perf_counter(): SUCCEED
        9052:20220405:221312.508 In get_counter_name() pdhIndex:2
        9052:20220405:221312.508 End of get_counter_name():SUCCEED
        9052:20220405:221312.508 In get_counter_name() pdhIndex:44
        9052:20220405:221312.508 End of get_counter_name():SUCCEED
        9052:20220405:221312.508 In add_perf_counter() counter:'\System\Processor Queue Length' interval:900
        9052:20220405:221312.508 add_perf_counter(): PerfCounter '\System\Processor Queue Length' successfully added
        9052:20220405:221312.508 End of add_perf_counter(): SUCCEED
        9052:20220405:221312.508 End of init_cpu_collector():SUCCEED
        9052:20220405:221312.508 In collect_perfstat()
        9052:20220405:221312.508 End of collect_perfstat()
        3284:20220405:221312.520 got [{"response":"success","data":[]}]
        3284:20220405:221312.520 In parse_list_of_checks()
        3284:20220405:221312.520 End of parse_list_of_checks():SUCCEED
        3284:20220405:221312.520 End of refresh_active_checks():SUCCEED
        3284:20220405:221312.520 In process_active_checks() server:'10.33.0.4' port:10051
        3284:20220405:221312.520 End of process_active_checks()
        3284:20220405:221312.520 In get_min_nextcheck()
        3284:20220405:221312.520 End of get_min_nextcheck():-1
        9052:20220405:221313.286 In collect_perfstat()
        9052:20220405:221313.286 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(1)\% Processor Time'. Value ignored
        9052:20220405:221313.286 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(0)\% Processor Time'. Value ignored
        9052:20220405:221313.286 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(_Total)\% Processor Time'. Value ignored
        9052:20220405:221313.286 End of collect_perfstat()
        3284:20220405:221313.299 In send_buffer() host:'10.33.0.4' port:10051 entries:0/100
        3284:20220405:221313.299 End of send_buffer():SUCCEED
        9052:20220405:221314.064 In collect_perfstat()
        9052:20220405:221314.064 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(1)\% Processor Time'. Value ignored
        9052:20220405:221314.064 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(0)\% Processor Time'. Value ignored
        9052:20220405:221314.064 PDH_CALC_NEGATIVE_DENOMINATOR error occurred in counterpath '\Processor(_Total)\% Processor Time'. Value ignored
        9052:20220405:221314.064 End of collect_perfstat()
        3284:20220405:221314.077 In send_buffer() host:'10.33.0.4' port:10051 entries:0/100
        3284:20220405:221314.077 End of send_buffer():SUCCEED
        9052:20220405:221314.843 In free_cpu_collector()
        9052:20220405:221314.843 End of free_cpu_collector()
      

      Some weird errors there, I see, but they should not affect service termination.

      Targeted host is a VM, OS Win7 x64, I can keep it in this state to reproduce the issue later.
      Agent from official MSI package x64, tested version 6.0.1

            zabbix.dev Zabbix Development Team
            zalex_ua Oleksii Zagorskyi
            Votes:
            0 Vote for this issue
            Watchers:
            1 Start watching this issue

              Created:
              Updated: