Ну, я не знаю, если это проблема метода или ведения журнала, но на производстве журнал заполняется той же строкой за небольшое количество миллисекунд.
Вотчасть моего журнала:
2018-09-19 15:23:36.070 INFO 1 --- [http-nio-8080-exec-105] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.117 INFO 1 --- [http-nio-8080-exec-101] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.150 INFO 1 --- [http-nio-8080-exec-104] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.192 INFO 1 --- [http-nio-8080-exec-96] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.224 INFO 1 --- [http-nio-8080-exec-86] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.266 INFO 1 --- [http-nio-8080-exec-92] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.298 INFO 1 --- [http-nio-8080-exec-107] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.339 INFO 1 --- [http-nio-8080-exec-80] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.372 INFO 1 --- [http-nio-8080-exec-89] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.414 INFO 1 --- [http-nio-8080-exec-84] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.447 INFO 1 --- [http-nio-8080-exec-105] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.487 INFO 1 --- [http-nio-8080-exec-101] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.519 INFO 1 --- [http-nio-8080-exec-104] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.559 INFO 1 --- [http-nio-8080-exec-96] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.590 INFO 1 --- [http-nio-8080-exec-86] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.638 INFO 1 --- [http-nio-8080-exec-92] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.670 INFO 1 --- [http-nio-8080-exec-107] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.711 INFO 1 --- [http-nio-8080-exec-80] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.743 INFO 1 --- [http-nio-8080-exec-89] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.784 INFO 1 --- [http-nio-8080-exec-84] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.815 INFO 1 --- [http-nio-8080-exec-105] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.856 INFO 1 --- [http-nio-8080-exec-101] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.887 INFO 1 --- [http-nio-8080-exec-104] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.928 INFO 1 --- [http-nio-8080-exec-96] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:36.961 INFO 1 --- [http-nio-8080-exec-86] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
2018-09-19 15:23:37.002 INFO 1 --- [http-nio-8080-exec-92] c.d.b.o.s.i.UserServiceImpl : Getting user - User(id=34, status=VALID, firstname=John, lastname=Doe)
Есть какие-нибудь подсказки по этому поводу?