27.03.18 10:03:43.687  LISTENER    ( ERROR   ) : o365(D): office365-user.handler:284  office365-user.handler() command: 'a' dn: 'uid=xxx,cn=users,dc=xxx,dc=com'
27.03.18 10:03:43.690  LISTENER    ( ERROR   ) : o365(I): azure_auth.get_http_proxies:338  proxy settings: {}
27.03.18 10:03:43.690  LISTENER    ( ERROR   ) : o365(I): azure_handler.__init__:170  service_plan_names=['OFFICE_BUSINESS']
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(D): office365-user.handler:309  new is enabled.
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(D): office365-user.handler:312  new_enabled=True old_enabled=False
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(I): office365-user.handler:318  new_enabled and not old_enabled -> NEW or REACTIVATED (uid=xxx,cn=users,dc=xxx,dc=com)
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(D): listener.create_user:98  udm_attrs={'mail': 'xxx@xxx.com', 'givenName': 'Joyce', 'displayName': 'xxx', 'mailPrimaryAddress': 'xxx@xxx.com', 'sn': 'xxx'}
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(I): azure_handler._create_object:286  Creating user with properties: {'passwordProfile': {'password': '******', 'forceChangePasswordNextLogin': False}, 'accountEnabled': True, 'surname': 'xxx', 'mailNickname': 'xxx', 'userPrincipalName': 'xxx@xxx.onmicrosoft.com', 'immutableId': 'MWNkOWViYjItYzVlMS0xMDM3LTk4ZjUtNzExMTM0NjBiODk5', 'otherMails': ['xxx@xxx.com'], 'displayName': 'xxx', 'givenName': 'Joyce', 'usageLocation': 'DE'}
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:352  Loading token from disk...
27.03.18 10:03:43.879  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:357  Token expired, retrieving now one from azure...
27.03.18 10:03:43.914  LISTENER    ( ERROR   ) : o365(D): azure_auth.retrieve_access_token:513  POST to URL='https://login.microsoftonline.com/xxx-account-id-xxx/oauth2/token' with data={'resource': 'https://graph.windows.net', 'client_assertion_type': 'urn:ietf:params:oauth:client-assertion-type:jwt-bearer', 'redirect_uri': u'https://ucs.xxx.com/univention/command/office365/authorize', 'client_assertion': ‚xxx-asertion-xxx‘‚, 'client_id': u’xxx-id—xxx’, 'scope': ['Directory.ReadWrite.All'], 'grant_type': 'client_credentials'}
27.03.18 10:03:44.355  LISTENER    ( ERROR   ) : o365(D): azure_auth.retrieve_access_token:522  response: {u'resource': u'https://graph.windows.net', u'access_token': u'xxx-access-token-xxx', u'expires_in': u'3599', u'ext_expires_in': u'0', u'token_type': u'Bearer', u'not_before': u'1522137515', u'expires_on': u'1522141415'}
27.03.18 10:03:44.357  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:359  Token valid until 2018-03-27T11:03:35.
27.03.18 10:03:44.358  LISTENER    ( ERROR   ) : o365(D): azure_handler.call_api:188  GET https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6&%24filter=immutableId+eq+%27MWNkOWViYjItYzVlMS0xMDM3LTk4ZjUtNzExMTM0NjBiODk5%27 data: None
27.03.18 10:03:44.841  LISTENER    ( ERROR   ) : o365(I): azure_handler.call_api:220  status: 200 (OK) (GET https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6&%24filter=immutableId+eq+%27MWNkOWViYjItYzVlMS0xMDM3LTk4ZjUtNzExMTM0NjBiODk5%27)
27.03.18 10:03:44.843  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:359  Token valid until 2018-03-27T11:03:35.
27.03.18 10:03:44.843  LISTENER    ( ERROR   ) : o365(D): azure_handler.call_api:188  POST https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6 data: {'passwordProfile': {'password': '******', 'forceChangePasswordNextLogin': False}, 'accountEnabled': True, 'surname': u'xxx', 'mailNickname': u'xxx', 'userPrincipalName': u'xxx@xxx.onmicrosoft.com', 'immutableId': u'MWNkOWViYjItYzVlMS0xMDM3LTk4ZjUtNzExMTM0NjBiODk5', 'otherMails': ['xxx@xxx.com'], 'displayName': u'xxx', 'givenName': u'Joyce', 'usageLocation': u'DE'}
27.03.18 10:03:46.312  LISTENER    ( ERROR   ) : o365(I): azure_handler.call_api:220  status: 201 (OK) (POST https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6)
27.03.18 10:03:46.314  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:359  Token valid until 2018-03-27T11:03:35.
27.03.18 10:03:46.314  LISTENER    ( ERROR   ) : o365(D): azure_handler.call_api:188  GET https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6&%24filter=userPrincipalName+eq+%27xxx%40xxx.onmicrosoft.com%27 data: None
27.03.18 10:03:46.526  LISTENER    ( ERROR   ) : o365(I): azure_handler.call_api:220  status: 200 (OK) (GET https://graph.windows.net/xxx-account-id-xxx/users?api-version=1.6&%24filter=userPrincipalName+eq+%27xxx%40xxx.onmicrosoft.com%27)
27.03.18 10:03:46.527  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:359  Token valid until 2018-03-27T11:03:35.
27.03.18 10:03:46.527  LISTENER    ( ERROR   ) : o365(D): azure_handler.call_api:188  GET https://graph.windows.net/xxx-account-id-xxx/subscribedSkus?api-version=1.6 data: None
27.03.18 10:03:46.797  LISTENER    ( ERROR   ) : o365(I): azure_handler.call_api:220  status: 200 (OK) (GET https://graph.windows.net/xxx-account-id-xxx/subscribedSkus?api-version=1.6)
UNIVENTION_DEBUG_BEGIN  : admin.handlers.base.__getitem__ key = UniventionOffice365Profile
UNIVENTION_DEBUG_END    : admin.handlers.base.__getitem__ key = UniventionOffice365Profile
27.03.18 10:03:46.820  LISTENER    ( ERROR   ) : o365(I): listener.assign_subscription:504  SubscriptionProfiles found for 'xxx': []
27.03.18 10:03:46.820  LISTENER    ( ERROR   ) : o365(W): listener.assign_subscription:506  No SubscriptionProfiles: using all available subscriptions.
27.03.18 10:03:46.820  LISTENER    ( ERROR   ) : o365(D): azure_handler._change_license:489  operation: 'add' user_id: u'xxx-user-id-xxx' sku_id: u'xxx-SKU-xxx' deactivate_plans=None
27.03.18 10:03:46.820  LISTENER    ( ERROR   ) : o365(D): azure_auth.get_access_token:359  Token valid until 2018-03-27T11:03:35.
27.03.18 10:03:46.820  LISTENER    ( ERROR   ) : o365(D): azure_handler.call_api:188  POST https://graph.windows.net/xxx-account-id-xxx/users/xxx-user-id-xxx/assignLicense?api-version=1.6 data: {'addLicenses': [{'skuId': u'xxx-SKU-xxx', 'disabledPlans': []}], 'removeLicenses': []}
27.03.18 10:03:47.099  LISTENER    ( ERROR   ) : o365(I): azure_handler.call_api:220  status: 400 (FAIL) Code: Request_BadRequest (POST https://graph.windows.net/xxx-account-id-xxx/users/xxx-user-id-xxx/assignLicense?api-version=1.6)
27.03.18 10:03:47.100  LISTENER    ( ERROR   ) : o365(E): azure_handler.__init__:144  Subscription with SKU xxx-SKU-xxx does not have any available licenses.
27.03.18 10:03:47.100  LISTENER    ( ERROR   ) : o365(W): listener.create_user:151  Could not add license for subscription u'xxx-user-id-xxx' to user u'xxx-SKU-xxx': Subscription with SKU xxx-SKU-xxx does not have any available licenses.
UNIVENTION_DEBUG_BEGIN  : admin.handlers.base.__getitem__ key = UniventionOffice365Data
UNIVENTION_DEBUG_END    : admin.handlers.base.__getitem__ key = UniventionOffice365Data
27.03.18 10:03:47.107  LISTENER    ( ERROR   ) : o365(I): office365-user.new_or_reactivate_user:252  User creation success. userPrincipalName: u'xxx@xxx.onmicrosoft.com' objectId: u'xxx-user-id-xxx' dn: uid=xxx,cn=users,dc=xxx,dc=com
Bitteschön 
Vielen Dank und beste Grüße,
Meg
P.S. Macht es im späteren Workflow einen unterschied, ob der Nutzer bei der Erstellung Office “zugewiesen” wird oder nachträglich?
P.P.S. Nutzer wurde angelegt. D.h. alles ist korrekt? (wgn der ERRORs)