Opened connection [connectionId{localValue:7, serverValue:2065}] to clustertest-shard-00-01.0h8fd.mongodb.net:27017
duration create 20 operation ... (ms)-1208
duration create operation ... (ms)-39
duration find ... (ms)-72
duration delete operation ... (ms)-59
duration delete all operation ... (ms)-43
duration update operation ... (ms)-81
10:37:54.532 [Thread-0] DEBUG org.springframework.boot.devtools.restart.classloader.RestartClassLoader - Created RestartClassLoader org.springframework.boot.devtools.restart.classloader.RestartClassLoader@4f593b27
. ____ _ __ _ _
/\\ / ___'_ __ _ _(_)_ __ __ _ \ \ \ \
( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
\\/ ___)| |_)| | | | | || (_| | ) ) ) )
' |____| .__|_| |_|_| |_\__, | / / / /
=========|_|==============|___/=/_/_/_/
:: Spring Boot :: (v2.6.4)
2022-03-10 10:37:54.996 INFO 2060 --- [ restartedMain] c.example.employee.EmployeeApplication : Starting EmployeeApplication using Java 16.0.1 on DESKTOP-5D1LJ47 with PID 2060 (C:\Users\User\git\javaSpringBoot_Viladoms-2\employee\target\classes started by User in C:\Users\User\git\javaSpringBoot_Viladoms-2\employee)
2022-03-10 10:37:54.998 INFO 2060 --- [ restartedMain] c.example.employee.EmployeeApplication : No active profile set, falling back to 1 default profile: "default"
2022-03-10 10:37:55.070 INFO 2060 --- [ restartedMain] .e.DevToolsPropertyDefaultsPostProcessor : Devtools property defaults active! Set 'spring.devtools.add-properties' to 'false' to disable
2022-03-10 10:37:55.071 INFO 2060 --- [ restartedMain] .e.DevToolsPropertyDefaultsPostProcessor : For additional web related logging consider setting the 'logging.level.web' property to 'DEBUG'
2022-03-10 10:37:55.595 INFO 2060 --- [ restartedMain] .s.d.r.c.RepositoryConfigurationDelegate : Bootstrapping Spring Data MongoDB repositories in DEFAULT mode.
2022-03-10 10:37:55.663 INFO 2060 --- [ restartedMain] .s.d.r.c.RepositoryConfigurationDelegate : Finished Spring Data repository scanning in 60 ms. Found 1 MongoDB repository interfaces.
2022-03-10 10:37:56.152 INFO 2060 --- [ restartedMain] trationDelegate$BeanPostProcessorChecker : Bean 'org.springframework.ws.config.annotation.DelegatingWsConfiguration' of type [org.springframework.ws.config.annotation.DelegatingWsConfiguration$$EnhancerBySpringCGLIB$$9099600] is not eligible for getting processed by all BeanPostProcessors (for example: not eligible for auto-proxying)
2022-03-10 10:37:56.239 INFO 2060 --- [ restartedMain] .w.s.a.s.AnnotationActionEndpointMapping : Supporting [WS-Addressing August 2004, WS-Addressing 1.0]
2022-03-10 10:37:56.756 INFO 2060 --- [ restartedMain] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat initialized with port(s): 8080 (http)
2022-03-10 10:37:56.775 INFO 2060 --- [ restartedMain] o.apache.catalina.core.StandardService : Starting service [Tomcat]
2022-03-10 10:37:56.775 INFO 2060 --- [ restartedMain] org.apache.catalina.core.StandardEngine : Starting Servlet engine: [Apache Tomcat/9.0.58]
2022-03-10 10:37:56.875 INFO 2060 --- [ restartedMain] o.a.c.c.C.[Tomcat].[localhost].[/] : Initializing Spring embedded WebApplicationContext
2022-03-10 10:37:56.875 INFO 2060 --- [ restartedMain] w.s.c.ServletWebServerApplicationContext : Root WebApplicationContext: initialization completed in 1803 ms
2022-03-10 10:37:57.130 INFO 2060 --- [ restartedMain] org.mongodb.driver.cluster : Cluster created with settings {hosts=[127.0.0.1:27017], srvHost=clustertest.0h8fd.mongodb.net, mode=MULTIPLE, requiredClusterType=REPLICA_SET, serverSelectionTimeout='30000 ms', requiredReplicaSetName='atlas-hce3fq-shard-0'}
2022-03-10 10:37:57.160 INFO 2060 --- [8fd.mongodb.net] org.mongodb.driver.cluster : Adding discovered server clustertest-shard-00-02.0h8fd.mongodb.net:27017 to client view of cluster
2022-03-10 10:37:57.239 INFO 2060 --- [8fd.mongodb.net] org.mongodb.driver.cluster : Adding discovered server clustertest-shard-00-01.0h8fd.mongodb.net:27017 to client view of cluster
2022-03-10 10:37:57.240 INFO 2060 --- [8fd.mongodb.net] org.mongodb.driver.cluster : Adding discovered server clustertest-shard-00-00.0h8fd.mongodb.net:27017 to client view of cluster
2022-03-10 10:37:57.399 INFO 2060 --- [ restartedMain] o.s.b.d.a.OptionalLiveReloadServer : LiveReload server is running on port 35729
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:3, serverValue:1780}] to clustertest-shard-00-02.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:5, serverValue:1780}] to clustertest-shard-00-02.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:6, serverValue:2095}] to clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:4, serverValue:2346}] to clustertest-shard-00-00.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:2, serverValue:2346}] to clustertest-shard-00-00.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.902 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.connection : Opened connection [connectionId{localValue:1, serverValue:2095}] to clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.903 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=clustertest-shard-00-00.0h8fd.mongodb.net:27017, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=377498600, setName='atlas-hce3fq-shard-0', canonicalAddress=clustertest-shard-00-00.0h8fd.mongodb.net:27017, hosts=[clustertest-shard-00-00.0h8fd.mongodb.net:27017, clustertest-shard-00-02.0h8fd.mongodb.net:27017, clustertest-shard-00-01.0h8fd.mongodb.net:27017], passives=[], arbiters=[], primary='clustertest-shard-00-01.0h8fd.mongodb.net:27017', tagSet=TagSet{[Tag{name='nodeType', value='ELECTABLE'}, Tag{name='provider', value='AWS'}, Tag{name='region', value='EU_CENTRAL_1'}, Tag{name='workloadType', value='OPERATIONAL'}]}, electionId=null, setVersion=7, topologyVersion=TopologyVersion{processId=6229bd3f0b20e0407a1b47ab, counter=4}, lastWriteDate=Thu Mar 10 10:38:00 CET 2022, lastUpdateTimeNanos=158890511411600}
2022-03-10 10:37:57.903 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=clustertest-shard-00-01.0h8fd.mongodb.net:27017, type=REPLICA_SET_PRIMARY, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=377484400, setName='atlas-hce3fq-shard-0', canonicalAddress=clustertest-shard-00-01.0h8fd.mongodb.net:27017, hosts=[clustertest-shard-00-00.0h8fd.mongodb.net:27017, clustertest-shard-00-02.0h8fd.mongodb.net:27017, clustertest-shard-00-01.0h8fd.mongodb.net:27017], passives=[], arbiters=[], primary='clustertest-shard-00-01.0h8fd.mongodb.net:27017', tagSet=TagSet{[Tag{name='nodeType', value='ELECTABLE'}, Tag{name='provider', value='AWS'}, Tag{name='region', value='EU_CENTRAL_1'}, Tag{name='workloadType', value='OPERATIONAL'}]}, electionId=7fffffff00000000000001b5, setVersion=7, topologyVersion=TopologyVersion{processId=6229be7eb98122e4895fce9b, counter=6}, lastWriteDate=Thu Mar 10 10:38:00 CET 2022, lastUpdateTimeNanos=158890511411600}
2022-03-10 10:37:57.903 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Monitor thread successfully connected to server with description ServerDescription{address=clustertest-shard-00-02.0h8fd.mongodb.net:27017, type=REPLICA_SET_SECONDARY, state=CONNECTED, ok=true, minWireVersion=0, maxWireVersion=13, maxDocumentSize=16777216, logicalSessionTimeoutMinutes=30, roundTripTimeNanos=377395100, setName='atlas-hce3fq-shard-0', canonicalAddress=clustertest-shard-00-02.0h8fd.mongodb.net:27017, hosts=[clustertest-shard-00-00.0h8fd.mongodb.net:27017, clustertest-shard-00-02.0h8fd.mongodb.net:27017, clustertest-shard-00-01.0h8fd.mongodb.net:27017], passives=[], arbiters=[], primary='clustertest-shard-00-01.0h8fd.mongodb.net:27017', tagSet=TagSet{[Tag{name='nodeType', value='ELECTABLE'}, Tag{name='provider', value='AWS'}, Tag{name='region', value='EU_CENTRAL_1'}, Tag{name='workloadType', value='OPERATIONAL'}]}, electionId=null, setVersion=7, topologyVersion=TopologyVersion{processId=6229bfa895d77450fcaa60fd, counter=3}, lastWriteDate=Thu Mar 10 10:38:00 CET 2022, lastUpdateTimeNanos=158890511411600}
2022-03-10 10:37:57.908 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Setting max election id to 7fffffff00000000000001b5 from replica set primary clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.909 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Setting max set version to 7 from replica set primary clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:57.909 INFO 2060 --- [ngodb.net:27017] org.mongodb.driver.cluster : Discovered replica set primary clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:58.237 INFO 2060 --- [ restartedMain] o.s.b.w.embedded.tomcat.TomcatWebServer : Tomcat started on port(s): 8080 (http) with context path ''
2022-03-10 10:37:58.246 INFO 2060 --- [ restartedMain] c.example.employee.EmployeeApplication : Started EmployeeApplication in 3.701 seconds (JVM running for 4.227)
2022-03-10 10:37:58.248 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : Welcome to the runner from commandLineRunner to test JPA mapping 1:n
2022-03-10 10:37:58.249 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : Data creation Employee started...
2022-03-10 10:37:58.576 INFO 2060 --- [ restartedMain] org.mongodb.driver.connection : Opened connection [connectionId{localValue:7, serverValue:2065}] to clustertest-shard-00-01.0h8fd.mongodb.net:27017
2022-03-10 10:37:59.458 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : duration create 20 operation ... (ms)-1208
2022-03-10 10:37:59.459 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : Data creation Employee complete...
2022-03-10 10:37:59.547 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : Data Employee query get [Employee [id=6227a5dd5c2cbb3a0a1866bb, name=Teresa, surname=Williams, age=59], Employee [id=6229c6f60184ed7f5d403673, name=Joan, surname=Jones, age=25], Employee [id=6229c6f60184ed7f5d403674, name=Anna, surname=Williams, age=28], Employee [id=6229c6f60184ed7f5d403675, name=Pere, surname=Williams Sarger, age=28], Employee [id=6229c6f60184ed7f5d403676, name=Pere, surname=Williams Sarger, age=28], Employee [id=6229c6f60184ed7f5d403677, name=Pere, surname=Williams Sarger, age=28], Employee [id=6229c6f60184ed7f5d403678, name=Pere, surname=Williams Sarger, age=28], Employee [id=6229c6f60184ed7f5d403679, name=Pere, surname=Williams Sarger, age=28], Employee [id=6229c6f60184ed7f5d40367a, name=Joan, surname=Jones, age=25], Employee [id=6229c6f60184ed7f5d40367b, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d40367c, name=Joan, surname=Jones, age=25], Employee [id=6229c6f70184ed7f5d40367d, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d40367e, name=Joan, surname=Jones, age=25], Employee [id=6229c6f70184ed7f5d40367f, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d403680, name=Joan, surname=Jones, age=25], Employee [id=6229c6f70184ed7f5d403681, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d403682, name=Joan, surname=Jones, age=25], Employee [id=6229c6f70184ed7f5d403683, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d403684, name=Joan, surname=Jones, age=25], Employee [id=6229c6f70184ed7f5d403685, name=Anna, surname=Williams, age=28], Employee [id=6229c6f70184ed7f5d403686, name=Joan, surname=Jones, age=25]]
2022-03-10 10:37:59.594 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : employees count: 21
2022-03-10 10:37:59.705 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : employees PereEmployee [id=6229c6f70184ed7f5d403687, name=Silvia, surname=Williams, age=28]
2022-03-10 10:37:59.764 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : duration create operation ... (ms)-39
2022-03-10 10:37:59.764 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : duration find ... (ms)-72
2022-03-10 10:37:59.764 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : duration delete operation ... (ms)-59
2022-03-10 10:37:59.844 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : duration delete all operation ... (ms)-43
2022-03-10 10:38:00.005 INFO 2060 --- [ restartedMain] c.e.employee.ApplicationCommandRunner : update Teresa from age 58 to 59 ...Employee [id=6229c6f70184ed7f5d403688, name=Teresa, surname=Williams, age=59]