I’m mystified at this point, I’ve never seen this problem.  If you want to figure out what is going on I would suggest debugging ListenerInfo.serviceChanged to see if the service event makes it into DS at all and if so why it doesn’t get to the correct dependency manager.  Otherwise I wonder if you can provide some kind of test project I could look at?

thanks
david jencks

On May 4, 2016, at 7:10 PM, David Leangen <apache@leangen.net> wrote:


Hi,

Thank you.

Here is the output for the UserAdmin:

karaf.log.1:2016-05-04 21:58:50,984 | DEBUG | pool-27-thread-1 | useradmin                        | 87 - org.knopflerfish.bundle.useradmin - 4.1.1 | BundleEvent INSTALLED - org.knopflerfish.bundle.useradmin
karaf.log.1:2016-05-04 21:58:51,280 | DEBUG | pool-27-thread-1 | useradmin                        | 87 - org.knopflerfish.bundle.useradmin - 4.1.1 | BundleEvent RESOLVED - org.knopflerfish.bundle.useradmin
karaf.log.1:2016-05-04 21:58:51,503 | DEBUG | pool-27-thread-1 | useradmin                        | 87 - org.knopflerfish.bundle.useradmin - 4.1.1 | BundleEvent STARTING - org.knopflerfish.bundle.useradmin
karaf.log.1:2016-05-04 21:58:51,543 | DEBUG | pool-27-thread-1 | useradmin                        | 87 - org.knopflerfish.bundle.useradmin - 4.1.1 | ServiceEvent REGISTERED - [org.osgi.service.useradmin.UserAdmin] - org.knopflerfish.bundle.useradmin
karaf.log.1:2016-05-04 21:58:51,544 | DEBUG | pool-27-thread-1 | useradmin                        | 87 - org.knopflerfish.bundle.useradmin - 4.1.1 | BundleEvent STARTED - org.knopflerfish.bundle.useradmin

Looks like it gets registered just fine.

The unsatisfied reference happens only when the UserAdmin is registered *after* the OAuthUserAdmin. If the UserAdmin is already registered, the OAuthUserAdmin gets activated just fine, with all its references satisfied.

It was my understanding that the ordering should not have any effect on startup, but here it clearly does. :-(


karaf@root()> scr:details net.leangen.expedition.integration.oauth2.credentials
Component Details
  Name                : net.leangen.expedition.integration.oauth2.credentials
  State               : ACTIVE
  Properties          : 
    useradmin.target=(!(method=oauth2.0))
References
  Reference           : useradmin
    State             : satisfied
    Multiple          : single
    Optional          : mandatory
    Policy            : static
    Service Reference : Bound Service ID 119



Cheers,
=David


On May 5, 2016, at 1:26 AM, David Jencks <david.a.jencks@gmail.com> wrote:

David,

Your components seem to be acting as I’d expect.  However the log output you show doesn’t show the service event for the plain UserAdmin getting registered, so we can’t tell what happens when it does.  I don’t see any ServiceEvents logged here, but since there are BundleEvents I’d expect that we’d see ServiceEvents too.

One of these days I will translate the state constants into words in the logging…. I can’t remember the meanings of the numbers either.

david jencks

On May 4, 2016, at 4:57 AM, Morgan Hautman <morgan.hautman@gmail.com> wrote:

Hi David,

Np. Here are the different states http://felix.apache.org/apidocs/scr/1.2.0/org/apache/felix/scr/Component.html

"The Component is a Component Factory ready to manage Component instances from configuration data received from the Configuration Admin Service (value is 64)."

Possible investigation method:
Maybe try to launch Karaf in debug mode doing "bin/karaf debug" and connect through remote debugging with your IDE.

You will see if the bundle starts or not (using your filter)

Hope it helps a bit :)

Regards,
Morgan

2016-05-04 12:07 GMT+02:00 David Leangen <apache@leangen.net>:

Thanks, Morgan, that worked. :-)


The output is very long, and I’m not sure what to look for, but here it is…

Perhaps this is the relevant line (though I don’t know what it means…):

Component can not be activated since it is in state 64


Cheers,
=David


karaf.log.1:2016-05-04 05:53:23,480 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleEvent INSTALLED - net.leangen.expedition.integration.oauth.provider
karaf.log.1:2016-05-04 05:53:24,027 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleEvent RESOLVED - net.leangen.expedition.integration.oauth.provider
karaf.log.1:2016-05-04 05:53:24,205 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleEvent STARTING - net.leangen.expedition.integration.oauth.provider
karaf.log.1:2016-05-04 05:53:24,207 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] active
karaf.log.1:2016-05-04 05:53:24,207 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] descriptor locations OSGI-INF/net.leangen.expedition.integration.oauth2.credentials.xml,OSGI-INF/ne
t.leangen.expedition.integration.oauth2.xml,OSGI-INF/net.leangen.expedition.integration.oauth2.useradmin.xml
karaf.log.1:2016-05-04 05:53:24,221 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] ComponentHolder created for net.leangen.expedition.integration.oauth2.credentials
karaf.log.1:2016-05-04 05:53:24,224 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] ComponentHolder created for net.leangen.expedition.integration.oauth2
karaf.log.1:2016-05-04 05:53:24,226 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] ComponentHolder created for net.leangen.expedition.integration.oauth2.useradmin
karaf.log.1:2016-05-04 05:53:24,226 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] May enable component holder net.leangen.expedition.integration.oauth2.credentials
karaf.log.1:2016-05-04 05:53:24,226 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] Enabling component holder net.leangen.expedition.integration.oauth2.credentials
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(-1)] Dependency Manager created useradmininterface=org.osgi.service.useradmin.UserA
dmin, filter=(!(method=oauth2.0)), policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=useradmin, field-option=replace, field-collect
ion-type=null
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(-1)] Component net.leangen.expedition.integration.oauth2.credentials created: DS=DS
13, implementation=net.leangen.expedition.integration.oauth2.client.ClientCredentialsService, immediate=false, default-enabled=true, factory=null, configurati
on-policy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[net.leangen.expedition.integration.oauth2.credentials]
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(-1)] Component net.leangen.expedition.integration.oauth2.credentials Services: scop
e=singleton, services=[net.leangen.expedition.integration.oauth2.model.ClientCredentials]
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(-1)] Component net.leangen.expedition.integration.oauth2.credentials Properties: {u
seradmin.target=(!(method=oauth2.0))}
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(-1)] Component can not be activated since it is in state 64
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Updating target filters
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)]  No existing service listener to unregister for dependency useradmin
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Setting target property for dependency useradmin to (!(method=oauth2.0))
karaf.log.1:2016-05-04 05:53:24,227 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] New service tracker for useradmin, initial active: false, previous references:
{}, classFilter: (objectClass=org.osgi.service.useradmin.UserAdmin), eventFilter (!(method=oauth2.0)), initialReferenceFilter (&(objectClass=org.osgi.service.
useradmin.UserAdmin)(!(method=oauth2.0)))
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] dm useradmin tracker reset (closed)
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | classNameFilter: (objectClass=org.osgi.service.useradmin.UserAdmin) event filter: (!(method=oauth2.0))
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] dm useradmin tracker opened
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] registering service listener for dependency useradmin
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Component enabled
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] ActivateInternal
karaf.log.1:2016-05-04 05:53:24,228 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Activating component from state 2
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Dependency not satisfied: useradmin
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.credentials(2)] Not all dependencies satisfied, cannot activate
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] May enable component holder net.leangen.expedition.integration.oauth2
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] Enabling component holder net.leangen.expedition.integration.oauth2
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(-1)] Dependency Manager created credsinterface=net.leangen.expedition.integration.oauth2.model.
ClientCredentials, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=creds, field-option=replace, field-collection-typ
e=null
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(-1)] Component net.leangen.expedition.integration.oauth2 created: DS=DS13, implementation=net.l
eangen.expedition.integration.oauth2.client.OauthService, immediate=false, default-enabled=true, factory=null, configuration-policy=optional, activate=activat
e, deactivate=deactivate, modified=null configuration-pid=[net.leangen.expedition.integration.oauth2]
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(-1)] Component net.leangen.expedition.integration.oauth2 Services: scope=singleton, services=[n
et.leangen.expedition.integration.oauth2.model.OAuth2]
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(-1)] Component net.leangen.expedition.integration.oauth2 Properties: {}
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(-1)] Component can not be activated since it is in state 64
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Updating target filters
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] No change in target property for dependency creds: currently registered: false
karaf.log.1:2016-05-04 05:53:24,229 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)]  No existing service listener to unregister for dependency creds
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Setting target property for dependency creds to null
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] New service tracker for creds, initial active: false, previous references: {}, classFilter:
(objectClass=net.leangen.expedition.integration.oauth2.model.ClientCredentials), eventFilter null, initialReferenceFilter (objectClass=net.leangen.expedition
.integration.oauth2.model.ClientCredentials)
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] dm creds tracker reset (closed)
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | classNameFilter: (objectClass=net.leangen.expedition.integration.oauth2.model.ClientCredentials) event filter: null
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] dm creds tracker opened
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] registering service listener for dependency creds
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Component enabled
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] ActivateInternal
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Activating component from state 2
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Dependency not satisfied: creds
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2(3)] Not all dependencies satisfied, cannot activate
karaf.log.1:2016-05-04 05:53:24,230 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] May enable component holder net.leangen.expedition.integration.oauth2.useradmin
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleComponentActivator : Bundle [68] Enabling component holder net.leangen.expedition.integration.oauth2.useradmin
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Dependency Manager created delegateinterface=org.osgi.service.useradmin.UserAdmi
n, filter=(!(method=oauth2.0)), policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=delegate, field-option=replace, field-collection-
type=null
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Dependency Manager created oauth2interface=net.leangen.expedition.integration.oa
uth2.model.OAuth2, filter=null, policy=static, cardinality=1..1, bind=null, unbind=null, updated=null, field=oauth2, field-option=replace, field-collection-ty
pe=null
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Component net.leangen.expedition.integration.oauth2.useradmin created: DS=DS13,
implementation=net.leangen.expedition.integration.oauth2.useradmin.OAuthUserAdminService, immediate=false, default-enabled=true, factory=null, configuration-p
olicy=optional, activate=activate, deactivate=deactivate, modified=null configuration-pid=[net.leangen.expedition.integration.oauth2.useradmin]
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Component net.leangen.expedition.integration.oauth2.useradmin Services: scope=si
ngleton, services=[org.osgi.service.useradmin.UserAdmin, net.leangen.expedition.integration.oauth2.OAuthUserAdmin]
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Component net.leangen.expedition.integration.oauth2.useradmin Properties: {metho
d=oauth2.0, delegate.target=(!(method=oauth2.0))}
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(-1)] Component can not be activated since it is in state 64
karaf.log.1:2016-05-04 05:53:24,231 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Updating target filters
karaf.log.1:2016-05-04 05:53:24,247 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)]  No existing service listener to unregister for dependency delegate
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Setting target property for dependency delegate to (!(method=oauth2.0))
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] New service tracker for delegate, initial active: false, previous references: {},
classFilter: (objectClass=org.osgi.service.useradmin.UserAdmin), eventFilter (!(method=oauth2.0)), initialReferenceFilter (&(objectClass=org.osgi.service.use
radmin.UserAdmin)(!(method=oauth2.0)))
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] dm delegate tracker reset (closed)
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | classNameFilter: (objectClass=org.osgi.service.useradmin.UserAdmin) event filter: (!(method=oauth2.0))
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] dm delegate tracker opened
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] registering service listener for dependency delegate
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] No change in target property for dependency oauth2: currently registered: false
karaf.log.1:2016-05-04 05:53:24,248 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)]  No existing service listener to unregister for dependency oauth2
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Setting target property for dependency oauth2 to null
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] New service tracker for oauth2, initial active: false, previous references: {}, c
lassFilter: (objectClass=net.leangen.expedition.integration.oauth2.model.OAuth2), eventFilter null, initialReferenceFilter (objectClass=net.leangen.expedition
.integration.oauth2.model.OAuth2)
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] dm oauth2 tracker reset (closed)
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | classNameFilter: (objectClass=net.leangen.expedition.integration.oauth2.model.OAuth2) event filter: null
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] dm oauth2 tracker opened
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] registering service listener for dependency oauth2
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Component enabled
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] ActivateInternal
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Activating component from state 2
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Dependency not satisfied: delegate
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Dependency not satisfied: oauth2
karaf.log.1:2016-05-04 05:53:24,249 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | [net.leangen.expedition.integration.oauth2.useradmin(4)] Not all dependencies satisfied, cannot activate
karaf.log.1:2016-05-04 05:53:24,250 | DEBUG | pool-29-thread-1 | provider                         | 68 - net.leangen.expedition.integration.oauth.provider - 0
.2.0.201605040539 | BundleEvent STARTED - net.leangen.expedition.integration.oauth.provider