Netxms Service not starting

Started by clifford, July 09, 2019, 09:23:01 AM

Previous topic - Next topic

clifford

Hi ,

Our Netxms Server service not starting. Below are the logs, please help.

[root@localhost ~]# tail -f /var/log/netxmsd
2019.07.09 11:48:16.897 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6410 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470094": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.925 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6413 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470286": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.926 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6414 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470350": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.929 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6415 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470414": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.931 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6416 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470478": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.932 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6417 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470542": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.936 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6418 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470606": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.959 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6421 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470798": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:16.960 *W* Failed to execute transformation script for object 2721340 "FIVE GARDEN" DCI 6422 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470862": Error 4 in line 1: Bad arithmetic conversion
2019.07.09 11:48:17.065 *W* Failed to execute transformation script for object 2721343 "THERGAON" DCI 5404 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470542": Error 4 in line 1: Bad arithmetic conversion


Thank you
Chandan

Victor Kirhenshtein

Hi,

those errors should not cause service to not start. Please try to run netxmsd from shell with -D9 option and post last lines of output.

Best regards,
Victor

clifford

Hi Victor,


We have executed netxmsd with D9 below is the out.

pollCount=4 requiredPolls=6
2019.07.22 12:53:58.438 *D* StatusPoll(Singapore_5850): polling interface 2704463 [10GE1/0/22]
2019.07.22 12:53:58.443 *D* Node(PADGHE)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469838): dwResult=0
2019.07.22 12:53:58.443 *D* DataCollector(): processing DC object 4280 ".1.3.6.1.2.1.31.1.1.1.10.27" owner=2694021 sourceNode=0
2019.07.22 12:53:58.443 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693861 (item_id,idata_timestamp,idata_value) VALUES (3919,1563780238,'-23')" [0 ms]
2019.07.22 12:53:58.444 *D* Node(PADGHE)->GetItemFromSNMP(.1.3.6.1.2.1.1.3.0): dwResult=0
2019.07.22 12:53:58.445 *D* Node(MIRCHOLI)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469710): dwResult=0
2019.07.22 12:53:58.445 *D* DataCollector(): processing DC object 4442 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470670" owner=2694021 sourceNode=0
2019.07.22 12:53:58.445 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693863 (item_id,idata_timestamp,idata_value) VALUES (3928,1563780238,'-17')" [0 ms]
2019.07.22 12:53:58.449 *D* Node(PADGHE)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.1.1.11.67108873): dwResult=0
2019.07.22 12:53:58.449 *D* DataCollector(): processing DC object 4443 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470862" owner=2694021 sourceNode=0
2019.07.22 12:53:58.450 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693861 (item_id,idata_timestamp,idata_value) VALUES (6704,1563780238,'38')" [0 ms]
2019.07.22 12:53:58.450 *D* DataCollector(): processing DC object 4459 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470414" owner=2694021 sourceNode=0
2019.07.22 12:53:58.450 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693861 (item_id,idata_timestamp,idata_value) VALUES (6703,1563780238,'144 days, 19:04')" [0 ms]
2019.07.22 12:53:58.456 *D* Node(MIRCHOLI)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469838): dwResult=0
2019.07.22 12:53:58.456 *D* DataCollector(): processing DC object 5417 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469390" owner=2694021 sourceNode=0
2019.07.22 12:53:58.456 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693863 (item_id,idata_timestamp,idata_value) VALUES (3929,1563780238,'-15')" [0 ms]
2019.07.22 12:53:58.456 *D* Node(MIRCHOLI)->GetItemFromSNMP(.1.3.6.1.2.1.1.3.0): dwResult=0
2019.07.22 12:53:58.463 *D* Node(MIRCHOLI)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.1.1.11.67108873): dwResult=0
2019.07.22 12:53:58.463 *D* DataCollector(): processing DC object 5418 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469454" owner=2694021 sourceNode=0
2019.07.22 12:53:58.463 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693863 (item_id,idata_timestamp,idata_value) VALUES (6708,1563780238,'43')" [0 ms]
2019.07.22 12:53:58.464 *D* DataCollector(): processing DC object 5423 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469774" owner=2694021 sourceNode=0
2019.07.22 12:53:58.464 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693863 (item_id,idata_timestamp,idata_value) VALUES (6707,1563780238,'148 days, 19:36')" [0 ms]
2019.07.22 12:53:58.467 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470094): dwResult=0
2019.07.22 12:53:58.467 *D* DataCollector(): processing DC object 5425 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469902" owner=2694021 sourceNode=0
2019.07.22 12:53:58.467 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3951,1563780238,'-6')" [0 ms]
2019.07.22 12:53:58.482 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470158): dwResult=0
2019.07.22 12:53:58.482 *D* DataCollector(): processing DC object 5426 ".1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469966" owner=2694021 sourceNode=0
2019.07.22 12:53:58.482 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3952,1563780238,'-8')" [0 ms]
2019.07.22 12:53:58.493 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470222): dwResult=0
2019.07.22 12:53:58.493 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3953,1563780238,'-7')" [0 ms]
2019.07.22 12:53:58.503 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470350): dwResult=0
2019.07.22 12:53:58.504 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3954,1563780238,'-6')" [0 ms]
2019.07.22 12:53:58.514 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469518): dwResult=0
2019.07.22 12:53:58.514 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3955,1563780238,'-16')" [0 ms]
2019.07.22 12:53:58.525 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469582): dwResult=0
2019.07.22 12:53:58.525 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3956,1563780238,'-19')" [0 ms]
2019.07.22 12:53:58.536 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469646): dwResult=0
2019.07.22 12:53:58.536 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3957,1563780238,'-18')" [0 ms]
2019.07.22 12:53:58.548 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469710): dwResult=0
2019.07.22 12:53:58.549 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3958,1563780238,'-17')" [0 ms]
2019.07.22 12:53:58.551 *D* Interface::StatusPoll(2704463,10GE1/0/22): new state from SNMP: adminState=1 operState=2
2019.07.22 12:53:58.551 *D* Interface::StatusPoll(2704463,10GE1/0/22): newStatus=0 oldStatus=0 pollCount=4 requiredPolls=6
2019.07.22 12:53:58.551 *D* StatusPoll(Singapore_5850): polling interface 2704464 [10GE1/0/23 (Stack-Port1/1)]
2019.07.22 12:53:58.551 *D* Node(TURBHE)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470670): dwResult=0
2019.07.22 12:53:58.552 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693009 (item_id,idata_timestamp,idata_value) VALUES (5526,1563780238,'-8')" [1 ms]
2019.07.22 12:53:58.570 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67469838): dwResult=0
2019.07.22 12:53:58.570 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (3959,1563780238,'-19')" [0 ms]
2019.07.22 12:53:58.572 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.2.1.31.1.1.1.6.27): dwResult=0
2019.07.22 12:53:58.572 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (4279,1563780238,'132496')" [0 ms]
2019.07.22 12:53:58.573 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.2.1.31.1.1.1.10.27): dwResult=0
2019.07.22 12:53:58.573 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (4280,1563780238,'1923560')" [0 ms]
2019.07.22 12:53:58.583 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470670): dwResult=0
2019.07.22 12:53:58.584 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (4442,1563780238,'-17')" [0 ms]
2019.07.22 12:53:58.594 *D* Node(TURBHE)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67471118): dwResult=0
2019.07.22 12:53:58.594 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2693009 (item_id,idata_timestamp,idata_value) VALUES (5527,1563780238,'-5')" [0 ms]
2019.07.22 12:53:58.595 *D* Node(CHAKAN)->GetItemFromSNMP(.1.3.6.1.4.1.2011.5.25.31.1.1.3.1.32.67470862): dwResult=0
2019.07.22 12:53:58.595 *D* [db.query           ] Successful sync query: "INSERT INTO idata_2694021 (item_id,idata_timestamp,idata_value) VALUES (4443,1563780238,'-10')" [0 ms]
2019.07.22 12:53:58.597 *D* AdHocScheduler: run scheduled task with id = 213, execution time = 1563780238
2019.07.22 12:53:58.598 *D* AdHocScheduler: sleeping for 43 seconds
2019.07.22 12:53:58.598 *D* [action             ] Executing scheduled action [1] for event (null) on node [251265]
2019.07.22 12:53:58.598 *D* [action             ] Executing action 1 (Link Down) of type SEND EMAIL
Segmentation fault

Victor Kirhenshtein

So server process is crashing. Please enable core dumps and either send coredump to us or get stack trace from it with gdb. You can also run netxmsd under debugger and get stack trace directly from there. Here is short instruction: https://wiki.netxms.org/wiki/Running_NetXMS_under_debugger

Best regards,
Victor