08.06.16 12:44:32.404  DEBUG_INIT
08.06.16 12:44:32.444  MAIN        ( PROCESS ) : Stopping UMC web server ...
08.06.16 12:44:32.459  MAIN        ( PROCESS ) : The UMC web server is still running. Will wait for 5 seconds
08.06.16 12:44:32.961  MAIN        ( PROCESS ) : Starting UMC web server ...
08.06.16 12:44:34.275  MAIN        ( INFO    ) : Reloading SAML service provider configuration
08.06.16 12:45:12.582  MAIN        ( INFO    ) : CPAuth/auth: got new auth request (::1:58191 <=> )
08.06.16 12:45:12.585  MAIN        ( INFO    ) : auth: request: command=/auth
08.06.16 12:45:12.590  MAIN        ( INFO    ) : CPAuth (::1:58191) found sessionid
08.06.16 12:45:12.591  MAIN        ( INFO    ) : CPAuth (::1:58191) sessionid='dde65cdc-2453-4637-ae31-0efc6297eed8'
08.06.16 12:45:12.609  MAIN        ( INFO    ) : CPAuth (::1:58191) found sessionid
08.06.16 12:45:12.610  MAIN        ( INFO    ) : CPAuth (::1:58191) sessionid='dde65cdc-2453-4637-ae31-0efc6297eed8'
08.06.16 12:45:12.610  MAIN        ( INFO    ) : auth: pushed request to queue - waiting for response
08.06.16 12:45:12.703  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b210
08.06.16 12:45:12.703  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: AUTH request
08.06.16 12:45:12.703  MAIN        ( INFO    ) : SessionClient(0x231b2d0): creating new session
08.06.16 12:45:12.790  MAIN        ( INFO    ) : Client.connect: SSL connection established
08.06.16 12:45:12.791  MAIN        ( INFO    ) : SessionClient(0x231b2d0): connected to UMC server
08.06.16 12:45:12.791  MAIN        ( INFO    ) : SessionClient(0x231b2d0): authenticate_user: sending authentication request for user u'__systemsetup__'
08.06.16 12:45:12.792  PROTOCOL    ( INFO    ) : Sending UMCP AUTH REQUEST 146538271260747-0
08.06.16 12:45:12.894  MAIN        ( INFO    ) : __verify_cert_cb: Got certificate subject: <X509Name object '/C=DE/ST=DE/L=DE/O=DE/OU=Univention Corporate Server/CN=Univention Corporate Server Root CA (ID=iBR9U6NY)/emailAddress=ssl@'>
08.06.16 12:45:12.898  MAIN        ( INFO    ) : __verify_cert_cb: Got certificate issuer: <X509Name object '/C=DE/ST=DE/L=DE/O=DE/OU=Univention Corporate Server/CN=Univention Corporate Server Root CA (ID=iBR9U6NY)/emailAddress=ssl@'>
08.06.16 12:45:12.899  MAIN        ( INFO    ) : __verify_cert_cb: errnum=0 depth=1 ok=1
08.06.16 12:45:12.918  MAIN        ( INFO    ) : __verify_cert_cb: Got certificate subject: <X509Name object '/C=DE/ST=DE/L=DE/O=DE/OU=Univention Corporate Server/CN=unassigned-hostname.unassigned-domain/emailAddress=ssl@'>
08.06.16 12:45:12.918  MAIN        ( INFO    ) : __verify_cert_cb: Got certificate issuer: <X509Name object '/C=DE/ST=DE/L=DE/O=DE/OU=Univention Corporate Server/CN=Univention Corporate Server Root CA (ID=iBR9U6NY)/emailAddress=ssl@'>
08.06.16 12:45:12.918  MAIN        ( INFO    ) : __verify_cert_cb: errnum=0 depth=0 ok=1
08.06.16 12:45:13.852  PARSER      ( INFO    ) : UMCP RESPONSE 146538271260747-0 parsed successfully
08.06.16 12:45:13.853  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538271260747-0
08.06.16 12:45:13.853  MAIN        ( PROCESS ) : SessionClient(0x231b2d0): _authenticated: success=True  status=200  message=None
08.06.16 12:45:13.860  MAIN        ( INFO    ) : auth: got response with status 200
08.06.16 12:45:13.861  MAIN        ( INFO    ) : auth: creating session with sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:13.863  MAIN        ( INFO    ) : CPAuth (::1:58191) found sessionid
08.06.16 12:45:13.864  MAIN        ( INFO    ) : CPAuth (::1:58191) sessionid='dde65cdc-2453-4637-ae31-0efc6297eed8'
08.06.16 12:45:14.734  MAIN        ( INFO    ) : CPSet (::1:58194) got new request
08.06.16 12:45:14.739  MAIN        ( INFO    ) : CPSet (::1:58194) found sessionid
08.06.16 12:45:14.739  MAIN        ( INFO    ) : CPSet (::1:58194) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:14.740  MAIN        ( INFO    ) : CPSet (::1:58194) found sessionid
08.06.16 12:45:14.741  MAIN        ( INFO    ) : CPSet (::1:58194) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:14.749  MAIN        ( INFO    ) : CPSet (::1:58194) found sessionid
08.06.16 12:45:14.749  MAIN        ( INFO    ) : CPSet (::1:58194) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:14.755  MAIN        ( INFO    ) : CPSet (::1:58194) queue(0x2314f80): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:14.763  MAIN        ( INFO    ) : CPSet (::1:58194) pushed request(0x2311b50) to queue(0x2314f80) - waiting for response
08.06.16 12:45:14.779  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b1d0
08.06.16 12:45:14.780  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:14.780  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538271474781-1)
08.06.16 12:45:14.780  PROTOCOL    ( INFO    ) : Sending UMCP SET REQUEST 146538271474781-1
08.06.16 12:45:15.429  PARSER      ( INFO    ) : UMCP RESPONSE 146538271474781-1 parsed successfully
08.06.16 12:45:15.429  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538271474781-1
08.06.16 12:45:15.429  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538271474781-1): status=200 queue=0x2314f80
08.06.16 12:45:15.431  MAIN        ( INFO    ) : CPSet (::1:58194) got response(0x231b290) from queue(0x2314f80): status=200
08.06.16 12:45:16.973  MAIN        ( INFO    ) : CPGet (::1:58195) got new request
08.06.16 12:45:16.979  MAIN        ( INFO    ) : CPGet (::1:58195) found sessionid
08.06.16 12:45:16.979  MAIN        ( INFO    ) : CPGet (::1:58195) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:16.979  MAIN        ( INFO    ) : CPGet (::1:58195) found sessionid
08.06.16 12:45:16.980  MAIN        ( INFO    ) : CPGet (::1:58195) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:16.988  MAIN        ( INFO    ) : CPGet (::1:58195) found sessionid
08.06.16 12:45:16.988  MAIN        ( INFO    ) : CPGet (::1:58195) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:16.989  MAIN        ( INFO    ) : CPGet (::1:58195) queue(0x231a7a0): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:16.989  MAIN        ( INFO    ) : CPGet (::1:58195) pushed request(0x2311c10) to queue(0x231a7a0) - waiting for response
08.06.16 12:45:16.995  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b550
08.06.16 12:45:16.996  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:16.996  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538271698181-2)
08.06.16 12:45:16.996  PROTOCOL    ( INFO    ) : Sending UMCP GET REQUEST 146538271698181-2
08.06.16 12:45:17.118  PARSER      ( INFO    ) : UMCP RESPONSE 146538271698181-2 parsed successfully
08.06.16 12:45:17.119  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538271698181-2
08.06.16 12:45:17.119  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538271698181-2): status=200 queue=0x231a7a0
08.06.16 12:45:17.120  MAIN        ( INFO    ) : CPGet (::1:58195) got response(0x231b690) from queue(0x231a7a0): status=200
08.06.16 12:45:17.359  MAIN        ( INFO    ) : CPGet (::1:58196) got new request
08.06.16 12:45:17.360  MAIN        ( INFO    ) : CPGet (::1:58196) found sessionid
08.06.16 12:45:17.360  MAIN        ( INFO    ) : CPGet (::1:58196) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.360  MAIN        ( INFO    ) : CPGet (::1:58196) found sessionid
08.06.16 12:45:17.360  MAIN        ( INFO    ) : CPGet (::1:58196) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.366  MAIN        ( INFO    ) : CPGet (::1:58196) found sessionid
08.06.16 12:45:17.370  MAIN        ( INFO    ) : CPGet (::1:58196) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.377  MAIN        ( INFO    ) : CPGet (::1:58196) queue(0x231a7e8): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.378  MAIN        ( INFO    ) : CPGet (::1:58196) pushed request(0x2311ed0) to queue(0x231a7e8) - waiting for response
08.06.16 12:45:17.436  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b590
08.06.16 12:45:17.436  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:17.436  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538271736207-3)
08.06.16 12:45:17.436  PROTOCOL    ( INFO    ) : Sending UMCP GET REQUEST 146538271736207-3
08.06.16 12:45:17.675  PARSER      ( INFO    ) : UMCP RESPONSE 146538271736207-3 parsed successfully
08.06.16 12:45:17.676  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538271736207-3
08.06.16 12:45:17.676  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538271736207-3): status=200 queue=0x231a7e8
08.06.16 12:45:17.681  MAIN        ( INFO    ) : CPGet (::1:58196) got response(0x231b8d0) from queue(0x231a7e8): status=200
08.06.16 12:45:17.796  MAIN        ( INFO    ) : CPGet (::1:58197) got new request
08.06.16 12:45:17.797  MAIN        ( INFO    ) : CPGet (::1:58197) found sessionid
08.06.16 12:45:17.798  MAIN        ( INFO    ) : CPGet (::1:58197) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.803  MAIN        ( INFO    ) : CPGet (::1:58197) found sessionid
08.06.16 12:45:17.803  MAIN        ( INFO    ) : CPGet (::1:58197) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.805  MAIN        ( INFO    ) : CPGet (::1:58197) found sessionid
08.06.16 12:45:17.806  MAIN        ( INFO    ) : CPGet (::1:58197) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.809  MAIN        ( INFO    ) : CPGet (::1:58197) queue(0x231a5f0): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:17.809  MAIN        ( INFO    ) : CPGet (::1:58197) pushed request(0x2311c10) to queue(0x231a5f0) - waiting for response
08.06.16 12:45:17.893  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b8d0
08.06.16 12:45:17.893  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:17.893  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538271780481-4)
08.06.16 12:45:17.893  PROTOCOL    ( INFO    ) : Sending UMCP GET REQUEST 146538271780481-4
08.06.16 12:45:18.076  PARSER      ( INFO    ) : UMCP RESPONSE 146538271780481-4 parsed successfully
08.06.16 12:45:18.076  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538271780481-4
08.06.16 12:45:18.076  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538271780481-4): status=200 queue=0x231a5f0
08.06.16 12:45:18.079  MAIN        ( INFO    ) : CPGet (::1:58197) got response(0x231b810) from queue(0x231a5f0): status=200
08.06.16 12:45:27.219  MAIN        ( INFO    ) : CPGet (::1:58199) got new request
08.06.16 12:45:27.220  MAIN        ( INFO    ) : CPGet (::1:58199) found sessionid
08.06.16 12:45:27.220  MAIN        ( INFO    ) : CPGet (::1:58199) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.220  MAIN        ( INFO    ) : CPGet (::1:58199) found sessionid
08.06.16 12:45:27.231  MAIN        ( INFO    ) : CPGet (::1:58199) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.233  MAIN        ( INFO    ) : CPGet (::1:58199) found sessionid
08.06.16 12:45:27.234  MAIN        ( INFO    ) : CPGet (::1:58199) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.239  MAIN        ( INFO    ) : CPGet (::1:58199) queue(0x231a290): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.240  MAIN        ( INFO    ) : CPGet (::1:58199) pushed request(0x2311c90) to queue(0x231a290) - waiting for response
08.06.16 12:45:27.304  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b850
08.06.16 12:45:27.304  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:27.304  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538272723269-5)
08.06.16 12:45:27.305  PROTOCOL    ( INFO    ) : Sending UMCP GET REQUEST 146538272723269-5
08.06.16 12:45:27.465  PARSER      ( INFO    ) : UMCP RESPONSE 146538272723269-5 parsed successfully
08.06.16 12:45:27.466  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538272723269-5
08.06.16 12:45:27.466  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538272723269-5): status=200 queue=0x231a290
08.06.16 12:45:27.468  MAIN        ( INFO    ) : CPGet (::1:58199) got response(0x231b8d0) from queue(0x231a290): status=200
08.06.16 12:45:27.640  MAIN        ( INFO    ) : CPCommand (::1:58200) got new request
08.06.16 12:45:27.641  MAIN        ( INFO    ) : CPCommand (::1:58200) found sessionid
08.06.16 12:45:27.641  MAIN        ( INFO    ) : CPCommand (::1:58200) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.641  MAIN        ( INFO    ) : CPCommand (::1:58200) found sessionid
08.06.16 12:45:27.642  MAIN        ( INFO    ) : CPCommand (::1:58200) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.649  MAIN        ( INFO    ) : CPCommand (::1:58200) found sessionid
08.06.16 12:45:27.650  MAIN        ( INFO    ) : CPCommand (::1:58200) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.655  MAIN        ( INFO    ) : CPCommand (::1:58200) queue(0x231a758): sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:45:27.655  MAIN        ( INFO    ) : CPCommand (::1:58200) pushed request(0x2311c50) to queue(0x231a758) - waiting for response
08.06.16 12:45:27.679  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: new request: 0x231b850
08.06.16 12:45:27.680  MAIN        ( INFO    ) : UMCP_Dispatcher: check_queue: normal request
08.06.16 12:45:27.680  MAIN        ( INFO    ) : SessionClient(0x231b2d0): sending request(146538272764827-6)
08.06.16 12:45:27.680  PROTOCOL    ( INFO    ) : Sending UMCP COMMAND REQUEST 146538272764827-6
08.06.16 12:45:37.831  PARSER      ( INFO    ) : UMCP RESPONSE 146538272764827-6 parsed successfully
08.06.16 12:45:37.832  PROTOCOL    ( INFO    ) : Received UMCP RESPONSE 146538272764827-6
08.06.16 12:45:37.832  MAIN        ( INFO    ) : SessionClient(0x231b2d0): got response(146538272764827-6): status=511 queue=0x231a758
08.06.16 12:45:37.835  MAIN        ( INFO    ) : CPCommand (::1:58200) got response(0x231b7d0) from queue(0x231a758): status=511
08.06.16 12:45:37.836  MAIN        ( PROCESS ) : CPCommand (::1:58200) response status code: 511
08.06.16 12:45:37.836  MAIN        ( PROCESS ) : CPCommand (::1:58200) response message: Connection to module process failed
08.06.16 12:45:37.836  MAIN        ( PROCESS ) : CPCommand (::1:58200) response result: None
08.06.16 12:45:46.763  MAIN        ( INFO    ) : CPGet (::1:58204) found sessionid
08.06.16 12:45:46.763  MAIN        ( INFO    ) : CPGet (::1:58204) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:46:16.756  MAIN        ( INFO    ) : CPGet (::1:58206) found sessionid
08.06.16 12:46:16.757  MAIN        ( INFO    ) : CPGet (::1:58206) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:46:46.779  MAIN        ( INFO    ) : CPGet (::1:58208) found sessionid
08.06.16 12:46:46.779  MAIN        ( INFO    ) : CPGet (::1:58208) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:47:16.761  MAIN        ( INFO    ) : CPGet (::1:58210) found sessionid
08.06.16 12:47:16.761  MAIN        ( INFO    ) : CPGet (::1:58210) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:47:46.798  MAIN        ( INFO    ) : CPGet (::1:58212) found sessionid
08.06.16 12:47:46.799  MAIN        ( INFO    ) : CPGet (::1:58212) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'
08.06.16 12:48:16.799  MAIN        ( INFO    ) : CPGet (::1:58214) found sessionid
08.06.16 12:48:16.799  MAIN        ( INFO    ) : CPGet (::1:58214) sessionid='580bab2a-d4e7-40a5-b6f0-3e87a6ca6a5d'