So I discovered that the waiting to connect message was because I had the "add new application" dialog from the controller open and it was firing that message as it waited for my application to connect. This is as designed.
Now my problem is, is that I cannot get my application to connect to the controller. I can see the logs of the application and profiler get all the way to "Sending Registration request with: Application Name [DotNetCoreApplicationTest], Tier Name [TestSite], Node Name [TestNode], Host Name [CGL073028] Node Unique Local ID [TestNode], Version [4.4.3.0]" and then the whole application hangs and freezes visual studio 2017.
I am using core 2.0 and the latest versions of the IDE. This happens in both a simple console application as well as a core asp.net website (out of the box config from VS).
Below are the agent logs, as I cannot attach the file as it is blocked. It hangs at the last statement.
Ideas?
************************************************************
2018-06-15 15:40:51.0115 100756 dotnet 1 4 Info LifetimeManager Initializing via agent bootstrap
2018-06-15 15:40:51.0537 100756 dotnet 1 4 Info LifetimeManager Running non-obfuscated agent
2018-06-15 15:40:51.1105 100756 dotnet 1 4 Info LifetimeManager Agent version: 4.4.3.0
2018-06-15 15:40:51.1213 100756 dotnet 1 4 Info LifetimeManager .NET Version: 4.0.30319.42000
2018-06-15 15:40:51.1213 100756 dotnet 1 4 Info LifetimeManager .NET Framework Installation Registry Release DWORD: 461808
2018-06-15 15:40:51.1213 100756 dotnet 1 4 Info ManagedAgentAPI mscorlib version: System.Private.CoreLib, Version=4.0.0.0, Culture=neutral, PublicKeyToken=7cec85d7bea7798e
2018-06-15 15:40:51.1399 100756 dotnet 1 4 Info ManagedAgentAPI mscorlib file version: 4.6.26328.01
2018-06-15 15:40:51.1399 100756 dotnet 1 4 Info ManagedAgentAPI using CLR4 function name resolver
2018-06-15 15:40:51.3559 100756 dotnet 1 4 Info ManagedAgentAPI Setting up CLR functionmap
2018-06-15 15:40:51.4536 100756 dotnet 1 4 Info ManagedAgentAPI Set CLR implemented methods map. Number of entries is: 576
2018-06-15 15:40:51.4536 100756 dotnet 1 4 Info ManagedAgentAPI Setting up CLR functionmap done
2018-06-15 15:40:51.4755 100756 dotnet 1 4 Info Configuration appDomainName=CoreConsoleTest appDomainId=1 iis-app=CoreConsoleTest.dll site=null port=null appPoolId= commandLine=C:\Users\dev\CoreConsoleTest\CoreConsoleTest\bin\Debug\netcoreapp2.0\CoreConsoleTest.dll
2018-06-15 15:40:52.3888 100756 dotnet 1 4 Info StandaloneConfigLoader {
"controller": {
"host": "********.saas.appdynamics.com",
"port": 443,
"account": "******",
"password": "***",
"ssl": true,
"enable_tls12": true,
"proxy": null
},
"application": {
"name": "DotNetCoreApplicationTest",
"tier": "TestSite",
"node": "TestNode"
},
"instrumentors": {
"enable": [],
"disable": []
}
}
2018-06-15 15:40:52.4063 100756 dotnet 1 4 Info Configuration Running Pro Agent
2018-06-15 15:40:52.5552 100756 dotnet 1 4 Info DotNetAgent ####################################################################################
2018-06-15 15:40:52.6339 100756 dotnet 1 4 Info AgentKernel Starting .NET Agent at 6/15/2018 9:40:52 PM ...
2018-06-15 15:40:52.6339 100756 dotnet 1 4 Info AgentKernel Time zone: Mountain Daylight Time
2018-06-15 15:40:52.8746 100756 dotnet 1 4 Info XMLConfigManager Orchestration is disabled - disabling virtualization resolvers by default.
2018-06-15 15:40:53.0886 100756 dotnet 1 4 Info XMLConfigManager Default IP Address Resolver found IP addresses [[10.0.75.1, 10.9.8.93, 172.30.246.17, 172.24.176.1]]
2018-06-15 15:40:53.1301 100756 dotnet 1 4 Info XMLConfigManager Full Agent Registration Info Resolver using selfService [False]
2018-06-15 15:40:53.1420 100756 dotnet 1 4 Info XMLConfigManager Full Agent Registration Info Resolver using application name [DotNetCoreApplicationTest]
2018-06-15 15:40:53.1420 100756 dotnet 1 4 Info XMLConfigManager Full Agent Registration Info Resolver using tier name [TestSite]
2018-06-15 15:40:53.1420 100756 dotnet 1 4 Info XMLConfigManager Full Agent Registration Info Resolver using node name [TestNode]
2018-06-15 15:40:53.1515 100756 dotnet 1 4 Info XMLConfigManager XML Controller Info Resolver found controller host [*************.saas.appdynamics.com]
2018-06-15 15:40:53.1515 100756 dotnet 1 4 Info XMLConfigManager XML Controller Info Resolver found controller port [443]
2018-06-15 15:40:53.1657 100756 dotnet 1 4 Info XMLConfigManager XML Agent Account Info Resolver using account name [singularity-agent@************]
2018-06-15 15:40:53.1657 100756 dotnet 1 4 Info XMLConfigManager XML Agent Account Info Resolver using account access key [****]
2018-06-15 15:40:53.1968 100756 dotnet 1 4 Info XMLConfigManager Configuration Channel is using ControllerInfo:: host:[************.saas.appdynamics.com] port:[443] sslEnabled:[true] keystoreFile:[DEFAULT:cacerts.jks] use-encrypted-credentials:[False] secureCredentialStoreFileName:[] secureCredentialStorePassword:[] use-ssl-client-auth:[unknown] asymmetricKeysStoreFilename:[] asymmetricKeysStorePassword:[] asymmetricKeyPassword:[] asymmetricKeyAlias:[] validation:[UNSPECIFIED]
2018-06-15 15:40:53.3832 100756 dotnet 1 4 Info AgentConfigWatchdog Agent overwrite set to False
2018-06-15 15:40:53.3832 100756 dotnet 1 4 Info AgentConfigWatchdog Parsed 0 filters from <sensitive-data-filters> section
2018-06-15 15:40:53.3832 100756 dotnet 1 4 Info AgentConfigWatchdog Parsed 0 filters from <sensitive-url-filters> section
2018-06-15 15:40:53.3979 100756 dotnet 1 4 Info XMLConfigManager Node will not be marked as historical at shutdown
2018-06-15 15:40:53.4205 100756 dotnet 1 4 Info AgentKernel Running non-obfuscated agent
2018-06-15 15:41:00.6087 100756 dotnet 1 4 Info AgentKernel No debugger attached
2018-06-15 15:41:00.7230 100756 dotnet 1 4 Info MetricHandler Metric Service is : [enabled].
2018-06-15 15:41:00.7339 100756 dotnet 1 4 Info LifeCycleManager Starting service [DynamicServiceManager]
2018-06-15 15:41:00.7339 100756 dotnet 1 4 Info DynamicServiceManager Loading dynamic service com.appdynamics.ee.service.analytics.Analytics
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Started service [DynamicServiceManager]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Starting service [AgentOperationService]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Service instantiated reflectively AgentOperationService :classcom.appdynamics.ee.agent.appagent.services.agentoperation.AgentOperationsService
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Started service [AgentOperationService]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Starting service [BCIEngine]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Started service [BCIEngine]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Starting service [CLRMetricsService]
2018-06-15 15:41:00.7639 100756 dotnet 1 4 Info LifeCycleManager Service instantiated reflectively CLRMetricsService :classcom.appdynamics.ee.agent.appagent.services.CLRService.CLRMetricsService
2018-06-15 15:41:00.7859 100756 dotnet 1 4 Info LifeCycleManager Started service [CLRMetricsService]
2018-06-15 15:41:00.7941 100756 dotnet 1 4 Info LifeCycleManager Starting service [SnapshotService]
2018-06-15 15:41:00.8085 100756 dotnet 1 4 Info TransactionSnapshotService Agent object monitoring is disabled
2018-06-15 15:41:00.8085 100756 dotnet 1 4 Info TransactionSnapshotService Coalesced Call-Stack Sampling is: disabled
2018-06-15 15:41:00.8085 100756 dotnet 1 4 Info TransactionSnapshotService CallStackSampler TryLock bounds in Micro Sec: 500
2018-06-15 15:41:00.8085 100756 dotnet 1 4 Info TransactionSnapshotService Enable-Contention-Tracking property set to: True
2018-06-15 15:41:00.9567 100756 dotnet 1 4 Info TopBackendsProcessor Started TopBackendsProcessor to process every [100] ms
2018-06-15 15:41:00.9567 100756 dotnet 1 4 Info TopBackendsProcessor Started TopBackendsReporter to report every [5] mins
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphConfigHandler Call Graph Config Changed callgraph-granularity-in-ms Value -
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphConfigHandler Call Graph Config Changed show-packages Value -
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphFilteringConfig Setting Callgraph package LOCAL always show []
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphConfigHandler Call Graph Config Changed dont-show-packages Value -
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphFilteringConfig Setting Callgraph package LOCAL excludes []
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info CallGraphConfigHandler Call Graph Config Changed hotspot-granularity-in-ms Value -
2018-06-15 15:41:00.9644 100756 dotnet 1 4 Info RequestSegmentDataReporter Request Segment Data reporter setup to report at interval [10000]
2018-06-15 15:41:00.9785 100756 dotnet 1 4 Info ApplicationStartupPolicy Startup policy False
2018-06-15 15:41:01.0088 100756 dotnet 1 4 Info LifeCycleManager Started service [SnapshotService]
2018-06-15 15:41:01.0088 100756 dotnet 1 4 Info LifeCycleManager Starting service [TransactionMonitoringService]
2018-06-15 15:41:01.0088 100756 dotnet 1 4 Info AsyncCorrelationADDRepository Started ADD registration thread to run every [10] seconds
2018-06-15 15:41:01.0088 100756 dotnet 1 4 Info AsyncCorrelationADDRepository Max ADD registrations allowed [500]
2018-06-15 15:41:01.0088 100756 dotnet 1 4 Info AsyncCorrelationADDRepository Reporting ADD registration failures every [5] minutes
2018-06-15 15:41:01.1693 100756 dotnet 1 4 Info SlowAndStallRequestMonitor Stall Request checker setup
2018-06-15 15:41:01.1693 100756 dotnet 1 4 Info SlowAndStallRequestMonitor Slow Request checker setup to check for requests slower than 300 ms, at interval 300 ms.
2018-06-15 15:41:01.1871 100756 dotnet 1 4 Info LoggerMessageProcessor LoggerMessageProcessor is scheduled to run: com.appdynamics.ee.util.csspecific.scheduler.AgentScheduledFutureImpl
2018-06-15 15:41:01.1961 100756 dotnet 1 4 Info AgentErrorProcessor AgentErrorProcessor is scheduled to run: com.appdynamics.ee.util.csspecific.scheduler.AgentScheduledFutureImpl
2018-06-15 15:41:01.1961 100756 dotnet 1 4 Info ErrorRegistry Error Registry initialized
2018-06-15 15:41:01.2243 100756 dotnet 1 4 Info ThreadCPUTimeCalculator BT CPU Monitoring will stay enabled ... Time taken to calculate Thread CPU Time for [1000] iteration [1] < [10]
2018-06-15 15:41:01.2282 100756 dotnet 1 4 Info ServiceEndPointADDRegistrar Starting ADD Registrar with registration period [10] secs
2018-06-15 15:41:01.2282 100756 dotnet 1 4 Info AsyncExitADDRegistrar Starting ADD Registrar with registration period [10] secs
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> setting [use-new-async-correlation] to True
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [use-flat-thread-chain-model] to True
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [bSyncMetricAggregator] to True
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [asyncAllMetrics] to
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [useNewEJB3NamingRule] to False
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [disableSocketCollection] to False
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [devModeSuspendCPM] to 500
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [jdbcLRUCacheSize] to 50
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [support-cross-app-correlation] to True
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> [support-federated-app-correlation] to True
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> ["+TransactionMonitorProperty.CHECK_BT_EXCLUDES_EARLY+"] to False
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> ["+TransactionMonitorProperty.disableInfoProviderMBean+"] to False
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> ["+TransactionMonitorProperty.infoProviderDetailMode+"] to False
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> ["+TransactionMonitorProperty.maxETELatencyTimestampRetentionInSeconds+"] to 300
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService TMS.Configure >> ["+TransactionMonitorProperty.eteLatencyTimestampScanRetentionIntervalInSeconds+"] to 60
2018-06-15 15:41:01.2443 100756 dotnet 1 4 Info TransactionMonitoringService Initialized [NEW] TransactionDelegate
2018-06-15 15:41:01.2659 100756 dotnet 1 4 Info LifeCycleManager Started service [TransactionMonitoringService]
2018-06-15 15:41:01.2974 100756 dotnet 1 4 Info ConfigurationChannel Sending node name: TestNode
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel Detected node meta info:
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:windows-process-id, Value:100756]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:dotnet-os-ver, Value:Microsoft Windows NT 6.2.9200.0]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:clr-architecture, Value:AMD64]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:appdomain, Value:CoreConsoleTest]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:process-identity, Value:SJRB\jsauter]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:configuration-file, Value:]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:appdomain-id, Value:1]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:ProcessID, Value:100756]
2018-06-15 15:41:01.4234 100756 dotnet 1 4 Info ConfigurationChannel [Name:iis-virtual-path, Value:/]
2018-06-15 15:41:01.4393 100756 dotnet 1 4 Info ConfigurationChannel Detected node meta info: [Name:appdynamics.ip.addresses, Value:10.0.75.1,10.9.8.93,172.30.246.17,172.24.176.1, Name:supportsDevMode, Value:true]
2018-06-15 15:41:01.4499 100756 dotnet 1 4 Info ConfigurationChannel Sending Registration request with: Application Name [DotNetCoreApplicationTest], Tier Name [TestSite], Node Name [TestNode], Host Name [CGL073028] Node Unique Local ID [TestNode], Version [4.4.3.0]
