Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

session serialization debug can get very slow #147

Open
jorgheymans opened this issue Oct 25, 2024 · 14 comments
Open

session serialization debug can get very slow #147

jorgheymans opened this issue Oct 25, 2024 · 14 comments

Comments

@jorgheymans
Copy link

Hunting down a session serde problem, it was painful having to wait almost a minute between clicks for the application to settle down and stop showing the blue scrollbar. Is there anything that can be done to make this useful again?

Also, from the output you see that one sessions serde times out at 30000ms but all the others complete succesfully at 40 seconds or more. Does that indicate a bug perhaps ?

2024-10-25T16:05:28.526+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 50007 ms with outcomes: [SUCCESS]
2024-10-25T16:06:15.823+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47137 ms with outcomes: [SUCCESS]
2024-10-25T16:07:03.298+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47319 ms with outcomes: [SUCCESS]
2024-10-25T16:07:47.688+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 44152 ms with outcomes: [SUCCESS]
2024-10-25T16:08:32.565+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 44724 ms with outcomes: [SUCCESS]
2024-10-25T16:09:17.728+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 44943 ms with outcomes: [SUCCESS]
2024-10-25T16:10:07.428+02:00  INFO  4121996 --- [dev_heymajo] [io-8080-exec-10] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 49528 ms with outcomes: [SUCCESS]
2024-10-25T16:10:37.299+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-4] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 42392 ms with outcomes: [SUCCESS]
2024-10-25T16:11:07.454+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-2] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 30000 ms with outcomes: [SERIALIZATION_TIMEOUT]
2024-10-25T16:11:16.959+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-4] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 37752 ms with outcomes: [SUCCESS]
2024-10-25T16:12:21.044+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47086 ms with outcomes: [SUCCESS]
2024-10-25T16:13:09.242+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 48050 ms with outcomes: [SUCCESS]
2024-10-25T16:13:57.514+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47459 ms with outcomes: [SUCCESS]
2024-10-25T16:14:46.272+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 48607 ms with outcomes: [SUCCESS]
2024-10-25T16:15:35.215+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 48795 ms with outcomes: [SUCCESS]
2024-10-25T16:16:25.924+02:00  INFO  4121996 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 50118 ms with outcomes: [SUCCESS]

@mcollovati
Copy link
Contributor

Serialization should not take so much time.
I remember there were a couple of fixes related to performance some time ago.
One reason could be that the serialization process cannot obtain the VaadinSession lock because of other process locking the session.

@jorgheymans
Copy link
Author

Can the session lock wait time be made visible in the logs somehow ?

@mcollovati
Copy link
Contributor

SessionSerializer logs at TRACE level failed attempts to perform optimistic serialization

@jorgheymans
Copy link
Author

here is some more output then:

2024-10-25T17:36:23.680+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-1] o.a.c.c.C.[Tomcat].[localhost].[/]       : Initializing Spring DispatcherServlet 'dispatcherServlet'
2024-10-25T17:36:23.681+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Initializing Servlet 'dispatcherServlet'
2024-10-25T17:36:23.684+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-1] o.s.web.servlet.DispatcherServlet        : Completed initialization in 3 ms
2024-10-25T17:36:42.360+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:36:42.368+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:36:42.371+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:36:42.776+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 405ms (143678 bytes)
2024-10-25T17:36:42.778+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:42.778+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:36:42.862+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:42.862+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key abe3f89e-f887-417d-9e65-1825c21b39db_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 82ms
2024-10-25T17:36:42.863+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 484 ms with outcomes: [SUCCESS]
2024-10-25T17:36:43.021+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Ignoring serialization request for session 20BA4484065B09C386D1B5529FC239CE as the session is already being serialized
2024-10-25T17:36:43.021+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:36:43.022+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:36:43.095+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 72ms (146378 bytes)
2024-10-25T17:36:43.095+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:43.095+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:36:43.135+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:43.135+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 5e9a010f-27ab-474c-ab7e-e1a1438416bc_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 39ms
2024-10-25T17:36:43.135+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 112 ms with outcomes: [SUCCESS]
2024-10-25T17:36:43.250+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Ignoring serialization request for session 20BA4484065B09C386D1B5529FC239CE as the session is already being serialized
2024-10-25T17:36:43.250+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:36:43.253+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:36:43.319+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 65ms (147882 bytes)
2024-10-25T17:36:43.320+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:43.320+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:36:43.352+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:43.352+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-9] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 4254746f-94e1-4a87-b6d6-57f7384f5dd3_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 32ms
2024-10-25T17:36:43.352+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-9] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 96 ms with outcomes: [SUCCESS]
2024-10-25T17:36:43.727+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:36:43.759+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 32ms (76966 bytes)
2024-10-25T17:36:43.760+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:36:43.760+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:37:04.724+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:37:04.725+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:37:04.726+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:37:04.728+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:37:07.483+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 2755ms (1674984 bytes)
2024-10-25T17:37:07.484+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:37:07.484+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:37:08.098+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 3370ms (3217503 bytes)
2024-10-25T17:37:08.098+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:37:08.098+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:37:52.955+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:37:52.955+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 72c18ad5-ab4c-46f6-be3a-f4d7ad239221_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 44857ms
2024-10-25T17:37:52.955+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-7] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 48227 ms with outcomes: [SUCCESS]
2024-10-25T17:37:53.107+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:37:53.108+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:37:53.108+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:37:53.111+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:37:53.952+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 841ms (1675728 bytes)
2024-10-25T17:37:53.953+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:37:53.953+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:37:55.009+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 1900ms (3219028 bytes)
2024-10-25T17:37:55.010+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:37:55.010+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:38:39.000+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:38:39.001+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 8bdb5014-4948-42bc-a136-71e0cb71d30b_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 43991ms
2024-10-25T17:38:39.001+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-7] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 45891 ms with outcomes: [SUCCESS]
2024-10-25T17:38:39.156+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:38:39.156+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:38:39.157+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:38:39.159+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:38:40.002+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 843ms (1675728 bytes)
2024-10-25T17:38:40.003+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-4] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:38:40.003+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:38:40.923+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 1766ms (3219028 bytes)
2024-10-25T17:38:40.923+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:38:40.923+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:39:24.391+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:39:24.391+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key d9b335ea-9a09-4e0c-8096-7366f2bb3640_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 43454ms
2024-10-25T17:39:24.391+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-7] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 45233 ms with outcomes: [SUCCESS]
2024-10-25T17:39:24.625+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:39:24.626+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:39:24.626+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:39:24.628+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:39:25.593+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 965ms (1642844 bytes)
2024-10-25T17:39:25.594+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:39:25.594+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:39:26.655+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 2029ms (3159632 bytes)
2024-10-25T17:39:26.656+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:39:26.656+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:40:10.396+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:10.397+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key b0737e26-506e-4dae-8110-ec484434dc4f_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 43741ms
2024-10-25T17:40:10.397+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-7] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 45770 ms with outcomes: [SUCCESS]
2024-10-25T17:40:10.545+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:40:10.546+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:40:10.547+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:40:10.548+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:40:11.505+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 957ms (1643116 bytes)
2024-10-25T17:40:11.506+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:11.506+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:40:12.447+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 1900ms (3160226 bytes)
2024-10-25T17:40:12.448+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:12.448+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE completed
2024-10-25T17:40:56.688+02:00 TRACE  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:56.688+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 07f99521-172d-4630-85dd-456135228ee0_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 44240ms
2024-10-25T17:40:56.688+02:00  INFO  4162275 --- [dev_heymajo] [nio-8080-exec-7] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 46138 ms with outcomes: [SUCCESS]
2024-10-25T17:40:56.839+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18
2024-10-25T17:40:56.840+02:00 DEBUG  4162275 --- [dev_heymajo] [nio-8080-exec-7] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 22dbf54b-cb86-4c00-b0e0-5a8fb73f6ae2_SOURCE:20BA4484065B09C386D1B5529FC239CE
2024-10-25T17:40:56.840+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 22dbf54b-cb86-4c00-b0e0-5a8fb73f6ae2_SOURCE:20BA4484065B09C386D1B5529FC239CE started
2024-10-25T17:40:56.841+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 started
2024-10-25T17:40:57.781+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 939ms (1643072 bytes)
2024-10-25T17:40:57.782+02:00 TRACE  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Serialized session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:57.782+02:00 DEBUG  4162275 --- [dev_heymajo] [rker-39540817-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed
2024-10-25T17:40:58.924+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 22dbf54b-cb86-4c00-b0e0-5a8fb73f6ae2_SOURCE:20BA4484065B09C386D1B5529FC239CE completed in 2084ms (3160211 bytes)
2024-10-25T17:40:58.924+02:00 TRACE  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 22dbf54b-cb86-4c00-b0e0-5a8fb73f6ae2_SOURCE:20BA4484065B09C386D1B5529FC239CE UIs: [ VaadinSession not accessible without locking ]
2024-10-25T17:40:58.925+02:00 DEBUG  4162275 --- [dev_heymajo] [ker-805017626-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-20BA4484065B09C386D1B5529FC239CE with distributed key 22dbf54b-cb86-4c00-b0e0-5a8fb73f6ae2_SOURCE:20BA4484065B09C386D1B5529FC239CE completed

@mcollovati
Copy link
Contributor

At a first look, it seems you are running debug serialization along with "production" serialization. So at the end of the request the session might be serialized both by the debugger and the servlet filter, causing locks to be held.

Also, looking at some of the serialization requests, it seems timings can be very different for the same session size

session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 2755ms (1674984 bytes)

session 20BA4484065B09C386D1B5529FC239CE with distributed key 1097e9a9-4548-4e5a-91e5-f6879a37ba18 completed in 965ms (1642844 bytes)

To better investigate, could you please run the application only with the debugger serializer on and check if the process is still so slow?

@jorgheymans
Copy link
Author

I have disabled the redis serialization, but it does not make a difference.

@mcollovati
Copy link
Contributor

Could you please attach the logs with redis backend disabled?

@jorgheymans
Copy link
Author

2024-10-28T08:05:23.028+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:05:23.033+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:05:23.514+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 480ms (143678 bytes)
2024-10-28T08:05:23.514+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:23.514+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:05:23.622+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:23.623+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 3fc54f49-9862-40ca-8eed-bf13bb09f2a0_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 106ms
2024-10-28T08:05:23.624+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-3] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 581 ms with outcomes: [SUCCESS]
2024-10-28T08:05:23.781+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:05:23.782+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:05:23.857+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 75ms (146378 bytes)
2024-10-28T08:05:23.858+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:23.858+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:05:23.902+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:23.902+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 48cb79e0-cc5a-4ffe-9d87-dd4020bff98a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 44ms
2024-10-28T08:05:23.902+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-3] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 119 ms with outcomes: [SUCCESS]
2024-10-28T08:05:24.020+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:05:24.022+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:05:24.087+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 65ms (147882 bytes)
2024-10-28T08:05:24.087+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:24.088+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:05:24.113+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:24.114+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 8710cc8e-5407-4c8c-9433-b36c9aee0df4_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 26ms
2024-10-28T08:05:24.114+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-3] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 88 ms with outcomes: [SUCCESS]
2024-10-28T08:05:39.018+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:05:39.019+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:05:42.479+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 3460ms (3215607 bytes)
2024-10-28T08:05:42.480+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:05:42.481+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:06:33.081+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:06:33.081+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 7ab2ca54-2bd8-4aab-acb8-15c20a747cfb_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 50600ms
2024-10-28T08:06:33.082+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 54061 ms with outcomes: [SUCCESS]
2024-10-28T08:06:33.256+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:06:33.256+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:06:34.965+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1709ms (3219003 bytes)
2024-10-28T08:06:34.965+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:06:34.965+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:07:21.552+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:07:21.553+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 4c9d1b94-03ac-462f-be88-94a055d8997e_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 46587ms
2024-10-28T08:07:21.553+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 48294 ms with outcomes: [SUCCESS]
2024-10-28T08:07:21.722+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:07:21.723+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:07:23.297+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1574ms (3219003 bytes)
2024-10-28T08:07:23.298+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:07:23.298+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:08:06.080+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:08:06.080+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2e78fd70-c8e1-4022-9d9f-d365adecf3e5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 42782ms
2024-10-28T08:08:06.080+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 44354 ms with outcomes: [SUCCESS]
2024-10-28T08:08:06.306+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:08:06.307+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:08:08.089+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1781ms (3159607 bytes)
2024-10-28T08:08:08.089+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:08:08.089+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:08:54.084+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:08:54.085+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 1693a9d6-f474-4eef-88e3-4cfe6b60e943_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 45996ms
2024-10-28T08:08:54.085+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47777 ms with outcomes: [SUCCESS]
2024-10-28T08:08:54.241+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:08:54.242+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:08:55.876+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1634ms (3160201 bytes)
2024-10-28T08:08:55.877+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:08:55.877+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:09:35.652+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:09:35.652+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 533ab9f7-fe86-4ac9-ae36-a030cbf951f5_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 39775ms
2024-10-28T08:09:35.653+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 41408 ms with outcomes: [SUCCESS]
2024-10-28T08:09:35.863+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:09:35.865+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:09:37.997+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 2131ms (3160186 bytes)
2024-10-28T08:09:37.998+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:09:37.998+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:10:26.179+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:10:26.179+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f88ffaa5-dd4f-47cf-a8ef-73a28c314b04_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 48181ms
2024-10-28T08:10:26.179+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 50310 ms with outcomes: [SUCCESS]
2024-10-28T08:10:26.451+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:10:26.452+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:10:28.324+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1871ms (3189090 bytes)
2024-10-28T08:10:28.324+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:10:28.324+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-2] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:11:13.913+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:11:13.913+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 60df68c9-dad8-4520-bfcc-62f133c14b8a_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 45589ms
2024-10-28T08:11:13.914+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47460 ms with outcomes: [SUCCESS]
2024-10-28T08:11:14.074+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:11:14.075+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:11:15.939+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1864ms (3189090 bytes)
2024-10-28T08:11:15.940+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:11:15.940+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-3] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:12:01.987+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:12:01.987+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key af499cc1-6503-4de5-aaf7-3e560e1129ee_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 46047ms
2024-10-28T08:12:01.987+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 47910 ms with outcomes: [SUCCESS]
2024-10-28T08:12:02.155+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:12:02.157+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:12:03.870+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 1712ms (3189111 bytes)
2024-10-28T08:12:03.870+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:12:03.870+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-4] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed
2024-10-28T08:12:45.200+01:00 TRACE  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialized session UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:12:45.200+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-5] c.v.k.s.s.SessionSerializer              : Deserialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-DESERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key f8d09e71-2343-4def-8a7c-5b684650a684_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 41330ms
2024-10-28T08:12:45.200+01:00  INFO  95528 --- [dev_heymajo] [nio-8080-exec-5] s.s.s.d.SerializationDebugRequestHandler : Session serialization attempt finished in 43040 ms with outcomes: [SUCCESS]
2024-10-28T08:14:09.026+01:00 DEBUG  95528 --- [dev_heymajo] [nio-8080-exec-3] c.v.k.s.s.SessionSerializer              : Starting asynchronous serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2583fdb6-85ea-4d66-9eed-d91f78d67f5c_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2
2024-10-28T08:14:09.027+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2583fdb6-85ea-4d66-9eed-d91f78d67f5c_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 started
2024-10-28T08:14:11.067+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialization of attributes [com.vaadin.flow.server.VaadinSession.springServlet, springServlet.lock, org.springframework.security.web.csrf.HttpSessionCsrfTokenRepository.CSRF_TOKEN, clusterKey] for session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2583fdb6-85ea-4d66-9eed-d91f78d67f5c_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed in 2040ms (3189002 bytes)
2024-10-28T08:14:11.068+01:00 TRACE  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Serialized session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2583fdb6-85ea-4d66-9eed-d91f78d67f5c_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 UIs: [ VaadinSession not accessible without locking ]
2024-10-28T08:14:11.068+01:00 DEBUG  95528 --- [dev_heymajo] [ker-139445891-1] c.v.k.s.s.SessionSerializer              : Optimistic serialization of session DEBUG-SERIALIZE-6EC491D8786DEA34B6C2B38E29469AF2 with distributed key 2583fdb6-85ea-4d66-9eed-d91f78d67f5c_SOURCE:6EC491D8786DEA34B6C2B38E29469AF2 completed

@mcollovati
Copy link
Contributor

It looks like most of the time is spent in deserialization.
Could you please activate TRACE level only for the following categories (to make it easier to inspect the logs)?

com.vaadin.kubernetes.starter.sessiontracker.serialization.TransientInjectableObjectInputStream=TRACE
com.vaadin.kubernetes.starter.sessiontracker.serialization.debug=TRACE

@jorgheymans
Copy link
Author

ims.log.gz

@mcollovati
Copy link
Contributor

@jorgheymans can you briefly describe how the UI is made up?
From the logs I can see that the for first two requests the deserialization is quite fast, but the third request takes a lot.
In that deserialization I can see a big graph (more than 65000 entries tracked counting objects and class descriptors) and it seems objects are inspected several times. For example, I can see 861 entries for TranslatedCodeListItem, 179 for SpringBasedBeanValidator.
I guess it could be a combination of Grid and forms.

Another thing that makes me wonder is to see an entry for SpringServlet, that should not be serialized.

@jorgheymans
Copy link
Author

That particular screen is 12 tabs, each with a dozen standard input fields and/or CrudGrids. There's a lot of reference data being loaded (the TranslatedCodelistItems). 179 SpringBasedBeanValidator instances seems wrong, there should not be more than about 20. We don't knowingly serialize SpringServlet though, how could it end up there?

@mcollovati
Copy link
Contributor

Did you already try to restrict the packages inspected by transient handler to see if it helps?

vaadin.serialization.transients.include-packages=eu.olaf

@jorgheymans
Copy link
Author

Yes, as per documentation it's set to the root package of our application.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

2 participants