Intune MDM connection in ISE continually fails with error 401: Unauthorized

[ben.posner]

we had both TAC and MS assist us in getting our ISE 2.6 enviroment connected to MS intune for MDM for mobile devices. it was a difficult process as we already had an MDM in place (MaaS360) and had to figure out the correct order of operations. it was working before last thanksgiving but then stopped after the holiday. im about at my wits end trying to get MS to look at it and hoped i could see if any one here had any ideas.

checking that the MDM connection is configured and working in the MDM setup diaglog of ISE says all systems go. but MDM reports show 401: Unathorized errors all day and night with NO valid checks happening.

all ISE nodes have internet access and can talk with MS.

here's a snippet of a PSN debug log with external-mdm turned up to TRACE

Executing the Heartbeat job : ping server -  mdmServerName = InTune 
2021-05-11 12:17:36,464 DEBUG  [Thread-92][] cisco.cpm.mdm.apiimpl.MDMVerifyServer -::::- Inside MDMVerifyServer.verify, connectToServer is called 
2021-05-11 12:17:36,464 DEBUG  [Thread-92][] cisco.cpm.mdm.apiimpl.MDMVerifyServer -::::- API version retrieved from MDM server : 2
2021-05-11 12:17:36,464 DEBUG  [Thread-92][] cisco.cpm.mdm.apiimpl.MDMVerifyServer -::::- 1. Connecting to the MDM server host fef.msua02.manage.microsoft.com using apiVersion 2
2021-05-11 12:17:36,464 DEBUG  [Thread-92][] cisco.cpm.mdm.util.MdmRESTClient -::::- sendGETRequestDom: start  HTTP request - connectionsUsed: 3, connectionsAvailable: 197
2021-05-11 12:17:36,464 INFO   [Thread-92][] cisco.cpm.mdm.util.MdmRESTClient -::::- GET: MDM Server URL: https://fef.msua02.manage.microsoft.com/StatelessNACService/ciscoise/mdminfo/?ise_api_version=2
2021-05-11 12:17:36,464 DEBUG  [Thread-92][] cisco.cpm.mdm.util.MdmRESTClient -::::- Proxy Config in request  = [,null,-1,nullnullnull]
2021-05-11 12:17:36,659 ERROR  [Thread-92][] cisco.cpm.mdm.util.MdmRESTClient -::::- Error message while connecting to MDM server : Failed to connect to MDM Server InTune : 401 Unauthorized
2021-05-11 12:17:36,660 DEBUG  [Thread-92][] cisco.cpm.mdm.util.MdmRESTClient -::::- sendGETRequestDom: end  HTTP request - connectionsUsed: 3, connectionsAvailable: 197
2021-05-11 12:17:36,660 ERROR  [Thread-92][] cisco.cpm.mdm.apiimpl.MDMVerifyServer -::::- Exception occurred while connecting to the MDM server Connection Failed: 401:Unauthorized: the MDM server is not reachable
2021-05-11 12:17:36,660 ERROR  [Thread-92][] cisco.cpm.mdm.api.MdmClient -::::- Connection Failed: 401:Unauthorized: the MDM server is not reachable
2021-05-11 12:17:36,660 ERROR  [Thread-92][] cisco.cpm.mdm.scheduler.MDMHeartbeat -::::- Exception occurred in MDM Heartbeat - thrown from MDMVerifyServer connect() method - Connection Failed: 401:Unauthorized: the MDM server is not reachable
2021-05-11 12:17:36,660 DEBUG  [Thread-92][] cisco.cpm.mdm.scheduler.MDMHeartbeat -::::- Updated MDM Reachability status in MDM server cache to false because of error while connecting to server
2021-05-11 12:17:36,660 ERROR  [Thread-92][] cisco.cpm.mdm.scheduler.MDMHeartbeat -::::- error occurred scheduling heartbeat job for mdm server name : InTune 
2021-05-11 12:17:37,224 INFO   [pool-67-thread-4][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:37,225 INFO   [pool-67-thread-4][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:38,256 INFO   [pool-67-thread-38][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:38,257 INFO   [pool-67-thread-38][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:38,897 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- Inside dequeue
2021-05-11 12:17:38,897 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- root exists
2021-05-11 12:17:38,897 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- No alarm file exist
2021-05-11 12:17:39,061 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:39,061 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:39,061 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:39,061 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.132.43.142
2021-05-11 12:17:39,061 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:40,211 INFO   [Thread-206][] ups.internaluser.dao.impl.InternalUserDAOImpl -::::- Cannot find user in database
2021-05-11 12:17:40,429 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:40,429 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:40,429 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:40,429 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=172.16.157.19
2021-05-11 12:17:40,429 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:40,958 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:40,958 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Start
2021-05-11 12:17:40,958 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Start, Mobile ID Type: null
2021-05-11 12:17:40,958 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Start, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.249.17.184
2021-05-11 12:17:40,958 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:42,792 INFO   [pool-67-thread-5][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:42,793 INFO   [pool-67-thread-5][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:42,991 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:42,991 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:42,991 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:42,991 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.249.17.184
2021-05-11 12:17:42,991 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:43,169 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:43,169 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:43,169 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:43,169 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.249.17.184
2021-05-11 12:17:43,169 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:43,284 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.FwUtil -::::- syncUpfwList : DB access allowed for ips: []
2021-05-11 12:17:43,556 INFO   [pool-67-thread-48][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:43,557 INFO   [pool-67-thread-48][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:43,825 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.FwUtil -::::- syncUpfwList : DB access disallowed for ips: []
2021-05-11 12:17:43,825 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.JGroupsFWUtil -::::- Applying Firewall rules for jGroup
2021-05-11 12:17:43,906 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:43,906 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:43,906 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:43,906 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.5.8.57
2021-05-11 12:17:43,906 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:44,041 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:44,041 TRACE  [PrRTEvents-Executor-2][] cisco.cpm.mdm.na.NetworkAccessData -::::- MDM NA Data Map(remove):: SessionID=ac1a0bf5000073b5609a75e9
2021-05-11 12:17:44,041 TRACE  [PrRTEvents-Executor-2][] cisco.cpm.mdm.na.NetworkAccessData -::::- MDM NA Data Map(remove):: SessionID=ac1a0bf5000073b5609a75e9 removed
2021-05-11 12:17:44,041 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Stop, NA Data removed for Session=ac1a0bf5000073b5609a75e9
2021-05-11 12:17:44,041 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:44,486 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.JGroupsFWUtil -::::- Applied Firewall rules for jGroup.
2021-05-11 12:17:44,487 INFO   [DefaultQuartzScheduler_Worker-4][] api.services.server.distribution.DistributionImpl -::::- Fetched nodegroups Information for hostName: hzniseplc1
2021-05-11 12:17:44,487 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.NodeGroupFWUtil -::::- NodeGroup object is null, which may mean that this is called by FW scheduler.
2021-05-11 12:17:44,487 INFO   [DefaultQuartzScheduler_Worker-4][] api.services.persistance.dao.DistributionDAO -::::- In DAO getHostConfigByNodeGroup method for Group Name: hazen_nodes
2021-05-11 12:17:44,491 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.NodeGroupFWUtil -::::- Applying Firewall rules for node group.
2021-05-11 12:17:45,863 INFO   [DefaultQuartzScheduler_Worker-4][] cisco.cpm.infrastructure.utils.NodeGroupFWUtil -::::- Applied Firewall rules for node group.
2021-05-11 12:17:47,278 INFO   [pool-67-thread-12][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:47,278 INFO   [pool-67-thread-12][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:48,251 INFO   [Thread-202][] ups.internaluser.dao.impl.InternalUserDAOImpl -::::- Cannot find user in database
2021-05-11 12:17:48,277 DEBUG  [Thread-152][] cisco.cpm.mdm.util.MDMUtil -::::- MDM server id from Endpoint :  
2021-05-11 12:17:48,277 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- mdm server name from the policy set:InTune
2021-05-11 12:17:48,277 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- mdm serverid is getting from policy set:2c156470-07fa-11eb-b90c-620ca6c4a2d4
2021-05-11 12:17:48,278 DEBUG  [Thread-152][] cisco.cpm.mdm.util.MDMUtil -::::- MDM Server ID: and MDM update time:1620753360103
2021-05-11 12:17:48,278 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- mdm server id in getInfoMapForValidDevice:2c156470-07fa-11eb-b90c-620ca6c4a2d4
2021-05-11 12:17:48,278 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- get MacList attrubutes are mac:20-E8-74-1E-17-FD phoneId:null phoneIdType:UNKNOWN sessionId:ac130af60005e784609abc3c mdmserverId:2c156470-07fa-11eb-b90c-620ca6c4a2d4
2021-05-11 12:17:48,278 DEBUG  [Thread-152][] cisco.cpm.mdm.api.MdmBaseApi -::::- Calling MDMHttpClient for getMacListForCriteria
2021-05-11 12:17:48,278 DEBUG  [Thread-152][] cisco.cpm.mdm.util.MdmServersCache -::::- mdm Guid: 2c156470-07fa-11eb-b90c-620ca6c4a2d4 is found in cache and status is: false
2021-05-11 12:17:48,279 WARN   [Thread-152][] cisco.cpm.mdm.api.MdmBaseApi -::::- Check if the MDM server InTune is reachable.
2021-05-11 12:17:48,279 DEBUG  [Thread-152][] cisco.cpm.mdm.api.MdmBaseApi -::::- arguments should contain key/value pairs but list is odd
2021-05-11 12:17:48,279 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- MACaddressIfDoesnotExistOrValid - MAC address is valid, no need to update, returning actual: 20-E8-74-1E-17-FD
2021-05-11 12:17:48,279 DEBUG  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- MDM PIP returns for endpoint sessionId ac130af60005e784609abc3c and macAddr 20-E8-74-1E-17-FD 
2021-05-11 12:17:48,279 TRACE  [Thread-152][] cisco.cpm.mdm.pip.MdmPartnerPIPHandler -::::- MDM PIP for attribute: MDM.MDMServerName, returned: {MDM.MDMServerName=InTune, MDM.MDMServerReachable=false, MDM.Model=, MDM.compliance=[Ljava.lang.String;@10bb4707, MDM.DeviceRegisterStatus=false, MDM.PinLockStatus=false, MDM.Provider=false, MDM.SerialNumber=, MDM.register_url=https://portal.manage.microsoft.com/networkaccesscontrol/index, MDM.IMEI=, MDM.DiskEncryptionStatus=false, MDM.ServerType=MobileDeviceManager, MDM.OsVersion=, MDM.PhoneNumber=, MDM.UDID=, MDM.JailBrokenStatus=false, MDM.UserNotified=false, MDM.MEID=, MDM.DaysSinceLastCheckin=, MDM.Manufacturer=, MDM.DeviceCompliantStatus=false, MDM.MDMFailureReason=, MDM.query_attribute=MDM.MDMServerName}
2021-05-11 12:17:48,279 DEBUG  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- Processing updateEndpoint: 20-E8-74-1E-17-FD
2021-05-11 12:17:48,281 DEBUG  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- MDM update time: 1620753360103 and MDM server name:null
2021-05-11 12:17:48,281 DEBUG  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- updating device mdm attributes - MDM Reachable value: false in endpoint
2021-05-11 12:17:48,281 DEBUG  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- MDMUpdateTime for Endpoint: 1620753468281
2021-05-11 12:17:48,293 INFO   [pool-67-thread-16][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:48,293 INFO   [pool-67-thread-16][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject
2021-05-11 12:17:48,297 DEBUG  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- updated device mdm attributes with macaddress: 20:e8:74:1e:17:fd
2021-05-11 12:17:48,297 TRACE  [MdmEventHandler-60-thread-4][] cisco.cpm.mdm.util.MDMUtil -::::- Sending Syslog - attributes: [Acs.EndPointMACAddress, 20-E8-74-1E-17-FD, MDM_LOG.IpAddress, null, Acs.UserName, null, MDM_LOG.SessionId, ac130af60005e784609abc3c, Acs.OperatingSystem, , Acs.RegisterStatus, Un-registered, Acs.FailureReason, Device is not registered with MDM]
2021-05-11 12:17:48,901 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- Inside dequeue
2021-05-11 12:17:48,901 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- root exists
2021-05-11 12:17:48,901 INFO   [Timer-11][] cisco.mnt.common.utility.AlarmMessageDiskQueue -::::- No alarm file exist
2021-05-11 12:17:48,940 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:48,940 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:48,940 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:48,940 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.5.8.57
2021-05-11 12:17:48,940 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:49,177 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:49,177 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:49,177 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:49,177 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=192.168.99.63
2021-05-11 12:17:49,177 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:49,489 INFO   [Thread-135][] ups.internaluser.dao.impl.InternalUserDAOImpl -::::- Cannot find user in database
2021-05-11 12:17:49,509 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE begin
2021-05-11 12:17:49,509 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update
2021-05-11 12:17:49,509 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Accounting Intermediate-Update, Mobile ID Type: null
2021-05-11 12:17:49,509 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, Accounting Intermediate-Update, UDID->Framed IP map put skipped for UDID=null, Framed IP=10.5.8.59
2021-05-11 12:17:49,509 DEBUG  [PrRTEvents-Executor-2][] cpm.mdm.na.event.MdmNetworkAccessEventListener -::::- MDM NA Event Listener, HandlingEvent: Type ACCOUNTING_RESPONSE completed
2021-05-11 12:17:50,344 INFO   [pool-67-thread-45][] cisco.epm.pdp.jms.MessageSynchronizer -::::- Length of objectMap is :1
2021-05-11 12:17:50,344 INFO   [pool-67-thread-45][] cisco.epm.pdp.jms.MessageSynchronizer -::::- ObjectMap is not null object 0 class Name com.cisco.epm.pap.api.vo.JMSMessageObject

[ben.posner]

also, its NOT this -> https://bst.cloudapps.cisco.com/bugsearch/bug/CSCvv43120/?rfs=iqvred
cuz it doesnt work. if it was just a visual bug in the logs i'd be less annoyed.

[Greg Gibbs · Accepted solution]

You might see if this bug is relevant and the workaround fixes the issue. If not, it would be best to open a TAC case to investigate further.

https://bst.cloudapps.cisco.com/bugsearch/bug/CSCvo45411

[ben.posner]

yeah, that seems to have resolved it. patch 9 has broken some other things now but at least my MDM connection works again!