Agent not creating log file

[Andrew]

Hello, I have a server running NetXMS version 2.0.5, and a client running 2.0.4 with the following config file:

##
## netxmsa.conf
##
MasterServers = 10.2.23.208,10.1.23.208,10.2.25.172,10.1.25.172
DataDirectory = C:\ProgramData\NetXMS
EnabledCiphers = 1
ExecTimeout = 300000
FileStore = C:\Users\Public\Downloads
ConfigIncludeDir = C:\ProgramData\NetXMS\netxmsa
LogFile = C:\ProgramData\NetXMS\netxmsa\var\netxmsa.log
# LogFile = {syslog}
RequireAuthentication = no
StartupDelay = 16
SubAgent = portcheck.nsm
SubAgent = winperf.nsm
SubAgent = wmi.nsm

The client agent starts, but doesn't not generate the log file C:\ProgramData\NetXMS\netxmsa\var\netxmsa.log.  The directory exists, and has the correct permissions.  Is there some other way I can get diagnostic information to find out why it is not writing out this file?  Thanks, Andrew

[zev]

I hope that name of your config for nxagentd(NX agent) is "nxagentd.conf", not "netxmsa.conf". What does agent writes if you start it from console like: "C:\ProgramData\NetXMS\bin\nxagentd -D6"

Config file for the server should be named "netxmsd.conf" and for the agent "nxagentd.conf"

[Andrew]

Here is the output when I specify the aforementioned config file and the -D6 switch.  I'm a little concerned about the lines

[08-Sep-2016 16:21:15.618] ?????????
and
[08-Sep-2016 16:21:35.119] External parameters providers poller thread will not start

I don't think I see anything that relates to the logging issue, but I the line "[08-Sep-2016 16:21:35.119] External parameters providers poller thread will not start" seems to be my original issue, and the reason I was trying to examine the log.  There is an external script, which if you run it from a command line returns legitimate value, but the DCI that calls it comes back as unsupported.

Do you have any insight into these two issues given the output?

---output---
[08-Sep-2016 16:21:15.431] Additional configs was loaded from C:\ProgramData\NetXMS\netxmsa
[08-Sep-2016 16:21:15.446] Debug level set to 6
[08-Sep-2016 16:21:15.446] Data directory: C:\ProgramData\NetXMS
[08-Sep-2016 16:21:15.446] Subagent API initialized
[08-Sep-2016 16:21:15.446] Validating ciphers
[08-Sep-2016 16:21:15.618]    AES-256 enabled
[08-Sep-2016 16:21:15.618]    Blowfish-256 disabled (config)
[08-Sep-2016 16:21:15.618]    IDEA disabled (config)
[08-Sep-2016 16:21:15.618]    3DES disabled (config)
[08-Sep-2016 16:21:15.618]    AES-128 disabled (config)
[08-Sep-2016 16:21:15.618]    Blowfish-128 disabled (config)
[08-Sep-2016 16:21:15.618] Crypto library initialized
[08-Sep-2016 16:21:15.618] ?????????
[08-Sep-2016 16:21:15.618] DB Library: Database driver "sqlite.ddr" loaded and initialized successfully
[08-Sep-2016 16:21:15.618] New DB connection opened: handle=00000000008C11B0
[08-Sep-2016 16:21:15.634] Local database opened successfully
[08-Sep-2016 16:21:15.634] Subagent "WINNT.NSM" loaded successfully
[08-Sep-2016 16:21:15.634] Subagent "portcheck.nsm" loaded successfully
[08-Sep-2016 16:21:16.523] WinPerf: 2857 counter indexes read
[08-Sep-2016 16:21:16.554] WinPerf: 2857 counter translations read
[08-Sep-2016 16:21:16.772] WinPerf: "\Memory\Free & Zero Page List Bytes" is supported
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A waiting for set change
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_B waiting for set change
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_C waiting for set change
[08-Sep-2016 16:21:17.880] Subagent "winperf.nsm" loaded successfully
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: set changed
[08-Sep-2016 16:21:17.880] Subagent "wmi.nsm" loaded successfully
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\System\Processor Queue Length" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\System\Processor Queue Length" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\System\Processor Queue Length" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\Processor(_Total)\% Processor Time" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\Processor(_Total)\% Processor Time" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\Processor(_Total)\% Processor Time" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\PhysicalDisk(_Total)\Avg. Disk Queue Length" added to query
[08-Sep-2016 16:21:17.880] WINPERF: CollectorThread_A: Counter "\PhysicalDisk(_Total)\% Disk Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(0)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(0)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(0)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(1)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(1)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(1)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(2)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(2)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(2)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(3)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(3)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(3)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(4)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(4)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(4)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(5)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(5)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(5)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(6)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(6)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(6)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(7)\% Processor Time" added to query
[08-Sep-2016 16:21:17.896] WINPERF: CollectorThread_A: Counter "\Processor(7)\% Processor Time" added to query
[08-Sep-2016 16:21:17.911] WINPERF: CollectorThread_A: Counter "\Processor(7)\% Processor Time" added to query
[08-Sep-2016 16:21:17.911] WINPERF: CollectorThread_A entered data collection loop
WAIT [................]
[08-Sep-2016 16:21:35.119] External parameters providers poller thread will not start
[08-Sep-2016 16:21:35.119] Data collection scheduler thread started
[08-Sep-2016 16:21:35.119] Data sender thread started
[08-Sep-2016 16:21:35.119] Database writer thread started
[08-Sep-2016 16:21:35.119] Thread pool DATACOLL initialized (min=1, max=64)
[08-Sep-2016 16:21:35.119] Data reconciliation thread started
[08-Sep-2016 16:21:35.119] PushConnector: named pipe created, waiting for connection
[08-Sep-2016 16:21:35.119] StorageDiscoveryConnector: named pipe created, waiting for connection
[08-Sep-2016 16:21:35.119] Trying to bind on 0.0.0.0:4700
[08-Sep-2016 16:21:35.119] Trying to bind on 127.0.0.1:28180
[08-Sep-2016 16:21:35.119] Trying to bind on [::]:4700
[08-Sep-2016 16:21:35.119] Session agent connector listening on port 28180
[08-Sep-2016 16:21:35.119] Listening on socket 0.0.0.0:4700
[08-Sep-2016 16:21:35.119] Listening on socket [::]:4700
[08-Sep-2016 16:21:36.133] NetXMS Agent started
Agent running. Press ESC to shutdown.
Agent shutting down...
[08-Sep-2016 16:21:39.939] Shutdown() called
[08-Sep-2016 16:21:39.955] WINPERF: Collector thread for counter set C terminated
[08-Sep-2016 16:21:39.955] Waiting for data collector thread termination
[08-Sep-2016 16:21:39.955] WINPERF: Collector thread for counter set B terminated
[08-Sep-2016 16:21:39.955] Stopping threads in thread pool DATACOLL
[08-Sep-2016 16:21:39.955] WINPERF: Collector thread for counter set A terminated
[08-Sep-2016 16:21:39.955] Maintenance thread for thread pool DATACOLL stopped
[08-Sep-2016 16:21:39.955] Data reconciliation thread stopped
[08-Sep-2016 16:21:39.955] Thread pool DATACOLL destroyed
[08-Sep-2016 16:21:39.955] Data collection scheduler thread stopped
[08-Sep-2016 16:21:39.955] Waiting for data sender thread termination
[08-Sep-2016 16:21:39.955] Data sender thread stopped
[08-Sep-2016 16:21:39.955] Waiting for database writer thread termination
[08-Sep-2016 16:21:39.970] Database writer thread stopped
[08-Sep-2016 16:21:39.970] Waiting for data reconciliation thread termination
[08-Sep-2016 16:21:39.970] Trap sender thread terminated
[08-Sep-2016 16:21:40.189] Session agent connector thread terminated
[08-Sep-2016 16:21:41.141] Session Watchdog thread terminated
[08-Sep-2016 16:21:41.141] Listener thread terminated
[08-Sep-2016 16:21:41.141] DB connection 00000000008C11B0 closed
[08-Sep-2016 16:21:41.141] NetXMS Agent stopped