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)