[2015-11-02T00:03:11,101+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T00:03:11,102+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T00:03:11,108+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T00:03:11,130+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T00:03:11,130+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T00:03:11,139+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T00:03:11,249+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T00:03:11,249+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T00:03:11,255+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T00:03:11,266+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T00:03:11,266+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T00:03:11,272+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T01:03:11,323+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T01:03:11,323+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T01:03:11,329+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T01:03:11,394+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T01:03:11,394+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T01:03:11,401+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T01:03:11,446+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T01:03:11,446+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T01:03:11,457+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T01:03:11,471+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T01:03:11,471+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T01:03:11,477+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T02:03:11,130+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T02:03:11,130+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T02:03:11,136+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T02:03:11,154+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T02:03:11,154+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T02:03:11,160+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T02:03:11,180+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T02:03:11,181+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T02:03:11,186+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T02:03:11,213+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T02:03:11,213+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T02:03:11,222+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T03:03:11,096+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T03:03:11,097+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T03:03:11,103+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T03:03:11,129+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T03:03:11,129+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T03:03:11,135+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T03:03:11,340+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T03:03:11,340+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T03:03:11,346+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T03:03:11,377+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T03:03:11,377+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T03:03:11,383+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T04:03:11,119+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T04:03:11,119+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T04:03:11,130+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T04:03:11,162+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T04:03:11,162+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T04:03:11,168+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T04:03:11,181+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T04:03:11,182+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T04:03:11,187+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T04:03:11,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T04:03:11,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T04:03:11,206+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T05:03:11,117+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T05:03:11,117+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T05:03:11,124+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T05:03:11,153+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T05:03:11,153+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T05:03:11,164+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T05:03:11,177+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T05:03:11,178+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T05:03:11,184+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T05:03:11,197+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T05:03:11,198+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T05:03:11,203+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T06:03:11,126+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T06:03:11,126+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T06:03:11,132+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T06:03:11,145+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T06:03:11,145+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T06:03:11,151+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T06:03:11,170+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T06:03:11,170+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T06:03:11,176+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T06:03:11,190+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T06:03:11,191+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T06:03:11,202+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T07:03:11,109+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T07:03:11,109+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T07:03:11,115+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T07:03:11,152+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T07:03:11,153+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T07:03:11,159+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T07:03:11,175+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T07:03:11,175+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T07:03:11,181+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T07:03:11,195+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T07:03:11,195+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T07:03:11,204+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T08:03:11,106+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T08:03:11,107+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T08:03:11,113+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T08:03:11,125+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T08:03:11,126+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T08:03:11,131+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T08:03:11,153+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T08:03:11,154+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T08:03:11,160+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T08:03:11,176+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T08:03:11,176+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T08:03:11,183+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T09:03:11,102+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T09:03:11,102+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T09:03:11,108+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T09:03:11,129+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T09:03:11,129+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T09:03:11,136+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T09:03:11,146+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T09:03:11,147+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T09:03:11,152+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T09:03:11,164+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T09:03:11,164+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T09:03:11,171+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T10:03:11,172+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T10:03:11,172+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T10:03:11,179+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T10:03:11,196+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T10:03:11,196+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T10:03:11,202+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T10:03:11,240+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T10:03:11,240+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T10:03:11,252+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T10:03:11,264+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T10:03:11,264+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T10:03:11,270+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T11:03:11,277+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T11:03:11,277+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T11:03:11,284+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T11:03:11,302+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T11:03:11,302+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T11:03:11,307+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T11:03:11,322+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T11:03:11,322+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T11:03:11,328+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T11:03:11,343+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T11:03:11,343+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T11:03:11,354+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:03:11,151+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:03:11,152+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:03:11,157+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:03:11,177+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:03:11,177+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:03:11,183+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:03:11,197+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:03:11,198+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:03:11,204+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:03:11,217+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:03:11,217+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:03:11,223+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:08,799+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:08,800+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:08,805+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:08,836+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:08,836+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:08,843+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:08,893+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:08,893+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:08,899+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:08,935+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:08,936+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.operationmanager.HttpRequestDetailsImpl - HttpRequestDetailsImpl() - scServerAddress: 192.168.8.197
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - finalizeWorkflow() - Request [config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, ERROR_MESSAGE, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], workflowId=924, pluginName=EMPTY_PLUGIN_NAME, token=d6bbc047-2d34-47ac-b82d-a2daaefa16b2]
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - queueRequest() - finalizeWorkflow
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - Trying to queue request: OperationRequestImpl [operationId=9a23dc0b-b537-40e0-ae20-2399fae727ef, config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, ERROR_MESSAGE, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], operationDetails=OperationDetailsImpl [workflowId=924, pluginName=EMPTY_PLUGIN_NAME, operationName=finalizeWorkflow, profileName=SCCASE, configName=ORA_mount_start, command=]]
[2015-11-02T12:56:08,942+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - queueRequest() - locked SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_finalizeWorkflow
[2015-11-02T12:56:08,984+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:08,984+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:08,990+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Preparing to execute operation: 9a23dc0b-b537-40e0-ae20-2399fae727ef
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorFactoryImpl - createOperationExecutor() - created operationExecutor.
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - trackOperation: Tracking=false for operationId: 9a23dc0b-b537-40e0-ae20-2399fae727ef
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - getOperationTimeout() - using timeout from config - 150
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - WatchableImpl() - time to live: 150000
[2015-11-02T12:56:09,157+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Operation 9a23dc0b-b537-40e0-ae20-2399fae727ef running and added to watchdog
[2015-11-02T12:56:09,186+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - call() - operationId: 9a23dc0b-b537-40e0-ae20-2399fae727ef
[2015-11-02T12:56:09,186+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=9a23dc0b-b537-40e0-ae20-2399fae727ef to RUNNING
[2015-11-02T12:56:09,186+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - PLUGIN=EMPTY_PLUGIN_NAME
[2015-11-02T12:56:09,186+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - factory=com.netapp.snapcreator.agent.nextgen.locking.LockFactoryImpl@4858abb5
[2015-11-02T12:56:09,186+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.logging.LoggerImpl - Logger Started for sccase.ora_mount_start.empty_plugin_name.924
[2015-11-02T12:56:09,187+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.context.ContextImpl - ContextImpl() - serverUrl: 192.168.8.197:8443
[2015-11-02T12:56:09,320+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - terminateAllLegacyWrappersForWorkflowId() - Cleaning up wid=924
[2015-11-02T12:56:09,320+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - cleanupContextViaContextManager() - finalized workflow with id=924
[2015-11-02T12:56:09,320+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=9a23dc0b-b537-40e0-ae20-2399fae727ef to COMPLETED
[2015-11-02T12:56:09,320+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - updateOperationStatus() - Removed lock [SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_finalizeWorkflow] for opId=9a23dc0b-b537-40e0-ae20-2399fae727ef (status=true)
[2015-11-02T12:56:09,320+0100] DEBUG [pool-2-thread-1] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - setResult() - Setting result for opId=9a23dc0b-b537-40e0-ae20-2399fae727ef
[2015-11-02T12:56:09,596+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - isTracked() - Tracked=false for operationId=9a23dc0b-b537-40e0-ae20-2399fae727ef
[2015-11-02T12:56:09,596+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - removeDoneWatchables() - removing WatchableImpl [stopTime=1446465519157, startTime=1446465369157, operationId=9a23dc0b-b537-40e0-ae20-2399fae727ef, operationName=finalizeWorkflow]
[2015-11-02T12:56:14,002+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:14,002+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:14,008+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T12:56:14,018+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T12:56:14,018+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T12:56:14,027+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,018+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:54,019+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:54,026+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,054+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:54,054+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:54,059+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,088+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:54,088+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:54,094+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,111+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:54,111+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:54,122+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,122+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.HttpRequestDetailsImpl - HttpRequestDetailsImpl() - scServerAddress: 192.168.8.197
[2015-11-02T13:00:54,122+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - execute() - Request [config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], workflowId=927, pluginName=EMPTY_PLUGIN_NAME, token=56b781fb-507a-4e4c-a6ea-21583394c451]
[2015-11-02T13:00:54,122+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - Trying to queue request: OperationRequestImpl [operationId=7032dfc1-6059-4f73-b6f7-701fc990295e, config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], operationDetails=OperationDetailsImpl [workflowId=927, pluginName=EMPTY_PLUGIN_NAME, operationName=execute, profileName=SCCASE, configName=ORA_mount_start, command=/opt/NetApp/OSAP/sc_oradb_recovery_log.sh mount wait 3]]
[2015-11-02T13:00:54,122+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - queueRequest() - locked SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_execute
[2015-11-02T13:00:54,134+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:54,134+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:54,140+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:00:54,238+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Preparing to execute operation: 7032dfc1-6059-4f73-b6f7-701fc990295e
[2015-11-02T13:00:54,238+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorFactoryImpl - createOperationExecutor() - created operationExecutor.
[2015-11-02T13:00:54,238+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - trackOperation: Tracking=false for operationId: 7032dfc1-6059-4f73-b6f7-701fc990295e
[2015-11-02T13:00:54,239+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - getOperationTimeout() - using timeout from config - 150
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - call() - operationId: 7032dfc1-6059-4f73-b6f7-701fc990295e
[2015-11-02T13:00:54,239+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - WatchableImpl() - time to live: 150000
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=7032dfc1-6059-4f73-b6f7-701fc990295e to RUNNING
[2015-11-02T13:00:54,239+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Operation 7032dfc1-6059-4f73-b6f7-701fc990295e running and added to watchdog
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - PLUGIN=EMPTY_PLUGIN_NAME
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - factory=com.netapp.snapcreator.agent.nextgen.locking.LockFactoryImpl@4858abb5
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.logging.LoggerImpl - Logger Started for sccase.ora_mount_start.empty_plugin_name.927
[2015-11-02T13:00:54,239+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.context.ContextImpl - ContextImpl() - serverUrl: 192.168.8.197:8443
[2015-11-02T13:00:54,276+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - run() - Running command: [/bin/sh, -c, /opt/NetApp/OSAP/sc_oradb_recovery_log.sh mount wait 3] in dir /opt/NetApp/scAgent4.1.2
[2015-11-02T13:00:59,152+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:00:59,152+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:00:59,158+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:04,171+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:04,171+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:04,177+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:04,935+0100] DEBUG [Thread-42] com.netapp.snapcreator.agent.nextgen.common.tools.StreamReader - run() - Finished reading stream stdout
[2015-11-02T13:01:04,936+0100] DEBUG [Thread-43] com.netapp.snapcreator.agent.nextgen.common.tools.StreamReader - run() - Finished reading stream stderr
[2015-11-02T13:01:04,936+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - executeCommand() - Command execution finished with exit code 0
[2015-11-02T13:01:04,936+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - run() - Command execution finished, returning result
[2015-11-02T13:01:04,936+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=7032dfc1-6059-4f73-b6f7-701fc990295e to COMPLETED
[2015-11-02T13:01:04,936+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - updateOperationStatus() - Removed lock [SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_execute] for opId=7032dfc1-6059-4f73-b6f7-701fc990295e (status=true)
[2015-11-02T13:01:04,936+0100] DEBUG [pool-2-thread-2] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - setResult() - Setting result for opId=7032dfc1-6059-4f73-b6f7-701fc990295e
[2015-11-02T13:01:06,033+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - isTracked() - Tracked=false for operationId=7032dfc1-6059-4f73-b6f7-701fc990295e
[2015-11-02T13:01:06,033+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - removeDoneWatchables() - removing WatchableImpl [stopTime=1446465804239, startTime=1446465654239, operationId=7032dfc1-6059-4f73-b6f7-701fc990295e, operationName=execute]
[2015-11-02T13:01:09,193+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,193+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,199+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,212+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,212+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,221+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,240+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,240+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,246+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,275+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,275+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,281+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,314+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,314+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,320+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,343+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,343+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,349+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,349+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.HttpRequestDetailsImpl - HttpRequestDetailsImpl() - scServerAddress: 192.168.8.197
[2015-11-02T13:01:09,349+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - execute() - Request [config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], workflowId=927, pluginName=EMPTY_PLUGIN_NAME, token=e52ae2a1-533e-45e8-a11b-3a3f7e59a9ef]
[2015-11-02T13:01:09,349+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - Trying to queue request: OperationRequestImpl [operationId=a105b11f-331d-45e6-8610-8e81871b5291, config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], operationDetails=OperationDetailsImpl [workflowId=927, pluginName=EMPTY_PLUGIN_NAME, operationName=execute, profileName=SCCASE, configName=ORA_mount_start, command=/opt/NetApp/OSAP/sc_oradb_recovery_log.sh recover ]]
[2015-11-02T13:01:09,350+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - queueRequest() - locked SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_execute
[2015-11-02T13:01:09,364+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:09,364+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:09,373+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:09,743+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Preparing to execute operation: a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:01:09,744+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorFactoryImpl - createOperationExecutor() - created operationExecutor.
[2015-11-02T13:01:09,744+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - trackOperation: Tracking=false for operationId: a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:01:09,744+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - getOperationTimeout() - using timeout from config - 150
[2015-11-02T13:01:09,744+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - WatchableImpl() - time to live: 150000
[2015-11-02T13:01:09,744+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Operation a105b11f-331d-45e6-8610-8e81871b5291 running and added to watchdog
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - call() - operationId: a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=a105b11f-331d-45e6-8610-8e81871b5291 to RUNNING
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - PLUGIN=EMPTY_PLUGIN_NAME
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - factory=com.netapp.snapcreator.agent.nextgen.locking.LockFactoryImpl@4858abb5
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.logging.LoggerImpl - Logger Started for sccase.ora_mount_start.empty_plugin_name.927
[2015-11-02T13:01:09,744+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.context.ContextImpl - ContextImpl() - serverUrl: 192.168.8.197:8443
[2015-11-02T13:01:09,763+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - run() - Running command: [/bin/sh, -c, /opt/NetApp/OSAP/sc_oradb_recovery_log.sh recover ] in dir /opt/NetApp/scAgent4.1.2
[2015-11-02T13:01:14,387+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:14,387+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:14,393+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:19,405+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:19,405+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:19,412+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:24,425+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:24,425+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:24,431+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:29,443+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:29,443+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:29,448+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:34,464+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:34,464+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:34,474+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:39,487+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:39,487+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:39,493+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:44,506+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:44,506+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:44,514+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:49,527+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:49,527+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:49,533+0100] DEBUG [qtp888655833-67] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:54,546+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:54,546+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:54,552+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:01:59,570+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:01:59,570+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:01:59,598+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:04,613+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:04,613+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:04,622+0100] DEBUG [qtp888655833-19] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:09,635+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:09,636+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:09,644+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:14,663+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:14,663+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:14,677+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:19,691+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:19,691+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:19,697+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:24,715+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:24,715+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:24,721+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:29,733+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:29,734+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:29,739+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:34,751+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:34,751+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:34,757+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:39,770+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:39,770+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:39,775+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:44,787+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:44,787+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:44,798+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:49,811+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:49,811+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:49,817+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:54,829+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:54,830+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:54,835+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:02:59,846+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:02:59,846+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:02:59,852+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:04,865+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:04,865+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:04,870+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:09,884+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:09,884+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:09,896+0100] DEBUG [qtp888655833-74] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:11,180+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:11,180+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:11,185+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:11,201+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:11,201+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:11,209+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:11,209+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:11,217+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:11,217+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:11,232+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:11,232+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:11,247+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:14,912+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:14,912+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:14,918+0100] DEBUG [qtp888655833-65] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:19,931+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:19,931+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:19,936+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:24,948+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:24,949+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:24,954+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:29,968+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:29,968+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:29,974+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:34,991+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:34,992+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:34,997+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:40,010+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:40,010+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:40,017+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:40,854+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - hasTimedOut() - operation has timed out with stopTime: 1446465819744
[2015-11-02T13:03:40,854+0100] WARN  [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - Operation a105b11f-331d-45e6-8610-8e81871b5291 exceeded its defined timeout. Cancelling it now.
[2015-11-02T13:03:40,875+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - cancel() - received request to cancel operation: true opId: a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:03:40,875+0100] ERROR [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - Failed to execute command: /opt/NetApp/OSAP/sc_oradb_recovery_log.sh recover . Reason: null
[2015-11-02T13:03:40,875+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - cancel() - a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:03:40,897+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - setResult() - Setting result for opId=a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:03:40,897+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=a105b11f-331d-45e6-8610-8e81871b5291 to CANCELLING
[2015-11-02T13:03:40,897+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - cancelWatchable() - Cancelled watchable for oId=a105b11f-331d-45e6-8610-8e81871b5291 : true
[2015-11-02T13:03:40,897+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - isTracked() - Tracked=false for operationId=a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:03:40,898+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - untrackedOperation() - operationId=a105b11f-331d-45e6-8610-8e81871b5291 not tracked by fileWatcher
[2015-11-02T13:03:40,898+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - run() - Failed to execute command: /opt/NetApp/OSAP/sc_oradb_recovery_log.sh recover 
java.lang.InterruptedException: null
	at java.lang.Object.wait(Native Method) ~[na:1.8.0_45]
	at java.lang.Thread.join(Thread.java:1245) [na:1.8.0_45]
	at java.lang.Thread.join(Thread.java:1319) [na:1.8.0_45]
	at com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor.executeCommand(CommandExecutor.java:71) [scAgent-4.1-core.jar:na]
	at com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor.run(CommandExecutor.java:44) [scAgent-4.1-core.jar:na]
	at com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl.executeCommand(OperationExecutorImpl.java:118) [scAgent-4.1-core.jar:na]
	at com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl.call(OperationExecutorImpl.java:80) [scAgent-4.1-core.jar:na]
	at com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl.call(OperationExecutorImpl.java:33) [scAgent-4.1-core.jar:na]
	at java.util.concurrent.FutureTask.run(FutureTask.java:266) [na:1.8.0_45]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) [na:1.8.0_45]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) [na:1.8.0_45]
	at java.lang.Thread.run(Thread.java:745) [na:1.8.0_45]
[2015-11-02T13:03:40,898+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.common.tools.CommandExecutor - run() - Command execution finished, returning result
[2015-11-02T13:03:40,898+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=a105b11f-331d-45e6-8610-8e81871b5291 to COMPLETED
[2015-11-02T13:03:40,898+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - updateOperationStatus() - Removed lock [SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_execute] for opId=a105b11f-331d-45e6-8610-8e81871b5291 (status=true)
[2015-11-02T13:03:40,898+0100] DEBUG [pool-2-thread-3] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - setResult() - Setting result for opId=a105b11f-331d-45e6-8610-8e81871b5291
[2015-11-02T13:03:45,033+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,033+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,039+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,053+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,053+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,059+0100] DEBUG [qtp888655833-42] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,081+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,082+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,091+0100] DEBUG [qtp888655833-64] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,119+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,120+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,125+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,153+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,153+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,159+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,193+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,193+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.operationmanager.HttpRequestDetailsImpl - HttpRequestDetailsImpl() - scServerAddress: 192.168.8.197
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - finalizeWorkflow() - Request [config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, ERROR_MESSAGE, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], workflowId=927, pluginName=EMPTY_PLUGIN_NAME, token=e455a1eb-2abc-4928-b255-0b4aca70f071]
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.AgentCoreImpl - queueRequest() - finalizeWorkflow
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - Trying to queue request: OperationRequestImpl [operationId=8df5e6af-980d-4072-8036-f1ba420997da, config=Config [parameters=LOG_TRACE_ENABLE, APP_CONF_PERSISTENCE, SC_USER_NAME, APP_DEFINED_RESTORE, NTAP_CONSISTENCY_GROUP_TIMEOUT, APP_DEFINED_CLONE, SC_SERVER_PORT, NTAP_CIFS_EXPORT_ENABLE, PRE_MOUNT_CMD01, PRE_MOUNT_CMD02, SC_AGENT_UNQUIESCE_TIMEOUT, CMODE_CLUSTER_USERS, NTAP_SNAPMIRROR_WAIT, NTAP_USERS, NTAP_NFS_EXPORT_ACCESS, LOG_NUM, NTAP_NUM_VOL_CLONES, RECORD_ID, CONFDIR, PROFILE_NAME, NTAP_CLONE_SECONDARY, SC_AGENT_LOG_ENABLE, VOLUMES, NTAP_SNAPSHOT_CLEANUP, ACTION, OSAP_CLONE_PATH, NTAP_LUN_CLONE_RESERVATION, USE_PROXY, USER_SNAP_NAME, NTAP_SNAPSHOT_RETENTIONS, NTAP_TIMEOUT, CONF_DIR, ERROR_MESSAGE, NTAP_SNAPVAULT_WAIT, OM_EVENT_GENERATE, NTAP_SNAPMIRROR_UPDATE, NTAP_OSSV_ENABLE, APP_AUTO_DISCOVERY, TRANSPORT, NTAP_SNAPMIRROR_USE_SNAPSHOT, APP_DEFINED_BACKUP, SC_AGENT, NTAP_PM_UPDATE, POST_MOUNT_CMD02, POST_MOUNT_CMD01, CMODE_SET, APP_DEFINED_MOUNT, NTAP_SNAPVAULT_UPDATE, NTAP_USE_EXTERNAL_SNAPSHOT, SNAP_TIME, SVNAME_COMBINED, SNAPDRIVE, NTAP_SNAPSHOT_NODELETE, NTAP_SNAPVAULT_SNAPSHOT, NTAP_CONSISTENCY_GROUP_WAFL_SYNC, CMODE_CLUSTER_NAME, NTAP_SNAPMIRROR_CASCADING_UPDATE, SC_AGENT_TIMEOUT, NTAP_SNAPSHOT_DELETE_BY_AGE_ONLY, NTAP_SNAPVAULT_NODELETE, ARCHIVE_LOG_RECURSIVE_SEARCH, PORT, NTAP_SNAPVAULT_RESTORE_WAIT, NTAP_SNAPSHOT_RESTORE_AUTO_DETECT, MOUNT_CMD01, CONFIG_NAME, NTAP_NFS_EXPORT_PERSISTENT, USE_GLOBAL_CONFIG, CMODE_SNAPSHOT_FORCE_DELETE, NTAP_EXTERNAL_SNAPSHOT_REGEX, ALLOW_DUPLICATE_SNAME, SNAP_TIMESTAMP_ONLY, ALLOW_IP_ADDR, OSAP_PATH, NTAP_VOL_CLONE_RESERVE, APP_IGNORE_ERROR, SNAME_COMBINED, SUCCESS_MSG, NTAP_PWD_PROTECTION, NTAP_CLONE_FOR_BACKUP, SNAME, NTAP_CONSISTENCY_GROUP_SNAPSHOT, SNAPDRIVE_DISCOVERY, --snap_name, NTAP_SNAPSHOT_DEPENDENCY_IGNORE, SC_ITERATION_ID, NTAP_ASUP_ERROR_ENABLE, NTAP_SNAPSHOT_DISABLE, CONFIG_TYPE, NTAP_PM_RUN_BACKUP, APP_DEFINED_UMOUNT, SC_AGENT_WATCHDOG_ENABLE, CONFNAME, APP_VM, ], operationDetails=OperationDetailsImpl [workflowId=927, pluginName=EMPTY_PLUGIN_NAME, operationName=finalizeWorkflow, profileName=SCCASE, configName=ORA_mount_start, command=]]
[2015-11-02T13:03:45,199+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - queueRequest() - locked SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_finalizeWorkflow
[2015-11-02T13:03:45,211+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:45,211+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:45,220+0100] DEBUG [qtp888655833-73] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Preparing to execute operation: 8df5e6af-980d-4072-8036-f1ba420997da
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorFactoryImpl - createOperationExecutor() - created operationExecutor.
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - call() - operationId: 8df5e6af-980d-4072-8036-f1ba420997da
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - trackOperation: Tracking=false for operationId: 8df5e6af-980d-4072-8036-f1ba420997da
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=8df5e6af-980d-4072-8036-f1ba420997da to RUNNING
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - PLUGIN=EMPTY_PLUGIN_NAME
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - getOperationTimeout() - using timeout from config - 150
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.context.ContextManagerImpl - factory=com.netapp.snapcreator.agent.nextgen.locking.LockFactoryImpl@4858abb5
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.watchdog.WatchableImpl - WatchableImpl() - time to live: 150000
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.logging.LoggerImpl - Logger Started for sccase.ora_mount_start.empty_plugin_name.927
[2015-11-02T13:03:45,291+0100] DEBUG [Thread-2] com.netapp.snapcreator.agent.nextgen.executionmanager.AsynchronousExecutionManagerImpl - submitRequest() - Operation 8df5e6af-980d-4072-8036-f1ba420997da running and added to watchdog
[2015-11-02T13:03:45,291+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.context.ContextImpl - ContextImpl() - serverUrl: 192.168.8.197:8443
[2015-11-02T13:03:45,303+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - terminateAllLegacyWrappersForWorkflowId() - Cleaning up wid=927
[2015-11-02T13:03:45,304+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.executionmanager.OperationExecutorImpl - cleanupContextViaContextManager() - finalized workflow with id=927
[2015-11-02T13:03:45,304+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - updateStatus() - Updating status for opId=8df5e6af-980d-4072-8036-f1ba420997da to COMPLETED
[2015-11-02T13:03:45,304+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.operationmanager.OperationManagerImpl - updateOperationStatus() - Removed lock [SCCASE_ORA_mount_start_EMPTY_PLUGIN_NAME_finalizeWorkflow] for opId=8df5e6af-980d-4072-8036-f1ba420997da (status=true)
[2015-11-02T13:03:45,304+0100] DEBUG [pool-2-thread-4] com.netapp.snapcreator.agent.nextgen.operationmanager.MonitorListImpl - setResult() - Setting result for opId=8df5e6af-980d-4072-8036-f1ba420997da
[2015-11-02T13:03:45,698+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.FileWatcherImpl - isTracked() - Tracked=false for operationId=8df5e6af-980d-4072-8036-f1ba420997da
[2015-11-02T13:03:45,699+0100] DEBUG [Watchdog] com.netapp.snapcreator.agent.nextgen.watchdog.WatchdogImpl - removeDoneWatchables() - removing WatchableImpl [stopTime=1446465975291, startTime=1446465825291, operationId=8df5e6af-980d-4072-8036-f1ba420997da, operationName=finalizeWorkflow]
[2015-11-02T13:03:50,232+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:50,232+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:50,238+0100] DEBUG [qtp888655833-75] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:03:50,249+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.AuthorizationValidatorImpl - isAuthorized() - Incoming request from user scServer2agent (192.168.8.197, 192.168.8.197)
[2015-11-02T13:03:50,249+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - Checking authorization for scServer2agent
[2015-11-02T13:03:50,255+0100] DEBUG [qtp888655833-44] com.netapp.snapcreator.agent.nextgen.security.ServiceIdentificationManagerImpl - isUserAuthorized() - scServer2agent isAuthorized = true
[2015-11-02T13:06:13,343+0100] DEBUG [Thread-45] com.netapp.snapcreator.agent.nextgen.common.tools.StreamReader - run() - Finished reading stream stderr
[2015-11-02T13:06:13,343+0100] DEBUG [Thread-44] com.netapp.snapcreator.agent.nextgen.common.tools.StreamReader - run() - Finished reading stream stdout