Discuss.FOLIO.org is no longer used. This is a static snapshot of the website as of February 14, 2023.

Failed to log on to Folio in the virtual machine environment

kzeng
28 Sep '21

Hi,

I failed to log on to FOLIO in the virtual machine environment.

Environment:

Windows10
Vagrant 2.2.18
VirtualBox 6.1
FOLIO packer_release_virtualbox.box (recent)
Google Chrome Version 94.0.4606.61 (Official Build) (64-bit)

vagrant up, After the virtual machine starts, access to the http://localhost:3000 can go to the login page, but with the account ‘diku_admin/admin’ login failed, occasionally successful login, it is really confusing.

The error message when the login failed is shown in the red section of the screenshot:

1
11522×1021 53.1 KB

2
21529×1025 66.9 KB

I searched the forum and couldn’t find the answer.
Who can guide me?

Thanks,
Kai

julianladisch
28 Sep '21

https://app.vagrantup.com/folio/boxes/release-core/versions/1.0.0-20210712.6632
suggests this Vagrantfile:

Vagrant.configure("2") do |config|
  config.vm.box = "folio/release-core"
  config.vm.box_version = "1.0.0-20210712.6632"
end

When I execute

vagrant up

it takes 7 GB RAM and several minutes. Then I can successfully login using diku_admin / admin on http://localhost:3000

Can you

vagrant ssh
and post the output of
tail -100 /var/log/folio/okapi/okapi.log
?

kzeng
29 Sep '21

Hi julianladisch,

My notebook:

RAM: 16.0 GB (15.4 GB Available) 
CPU: Intel (R) Cole (TM) i5-6300U CPU @ 2.40GHz 2.50GHz

My OKAPI Log:

vagrant@vagrant:~$ tail -100 /var/log/folio/okapi/okapi.log
2021-09-29T10:02:35,817 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.cipher.suites = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.enabled.protocols = [TLSv1.2]
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.endpoint.identification.algorithm = https
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.key.password = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.keymanager.algorithm = SunX509
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.keystore.location = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.keystore.password = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.keystore.type = JKS
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.protocol = TLSv1.2
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.provider = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.secure.random.implementation = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.trustmanager.algorithm = PKIX
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.truststore.location = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.truststore.password = null
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      ssl.truststore.type = JKS
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5      value.deserializer = class org.apache.kafka.common.serialization.StringDeserializer
2021-09-29T10:02:35,818 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5
2021-09-29T10:02:36,218 INFO  TcpPortWaiting       Try connect to service mod-kb-ebsco-java-3.7.0 at 10.0.2.15:9179 count 76
2021-09-29T10:02:36,338 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:36.337 [vert.x-worker-thread-1] INFO  ppInfoParser$AppInfo [569764eqId] Kafka version: 2.5.0
2021-09-29T10:02:36,448 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:36.447 [vert.x-worker-thread-1] INFO  ppInfoParser$AppInfo [569874eqId] Kafka commitId: 66563e712b0b9f84
2021-09-29T10:02:36,452 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:36.450 [vert.x-worker-thread-1] INFO  ppInfoParser$AppInfo [569877eqId] Kafka startTimeMs: 1632909756337
2021-09-29T10:02:36,569 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:36.568 [vert.x-kafka-consumer-thread-11] INFO  KafkaConsumer        [569995eqId] [Consumer clientId=consumer-DI_MARC_BIB_FOR_UPDATE_RECEIVED.mod-source-record-storage-5.0.5-12, groupId=DI_MARC_BIB_FOR_UPDATE_RECEIVED.mod-source-record-storage-5.0.5] Subscribed to pattern: 'folio\.Default\.\w{1,}\.DI_MARC_BIB_FOR_UPDATE_RECEIVED'
2021-09-29T10:02:36,674 INFO  DockerModuleHandle   mod-password-validator-2.0.2 10:02:36.527 [main] INFO  org.folio.pv.ModPasswordValidatorApplication - No active profile set, falling back to default profiles: default
2021-09-29T10:02:36,749 INFO  TcpPortWaiting       Try connect to service mod-orders-12.0.3 at 10.0.2.15:9161 count 77
2021-09-29T10:02:36,966 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:36.965 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [570392eqId] Consumer created - id: 1 subscriptionPattern: SubscriptionDefinition(eventType=DI_SRS_MARC_BIB_RECORD_CREATED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_SRS_MARC_BIB_RECORD_CREATED)
2021-09-29T10:02:37,608 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.607 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571034eqId] Consumer created - id: 2 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_INSTANCE_CREATED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_INSTANCE_CREATED)
2021-09-29T10:02:37,650 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.647 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571074eqId] Consumer created - id: 3 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_INSTANCE_UPDATED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_INSTANCE_UPDATED)
2021-09-29T10:02:37,717 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.715 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571142eqId] Consumer created - id: 4 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_HOLDING_CREATED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_HOLDING_CREATED)
2021-09-29T10:02:37,741 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.739 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571166eqId] Consumer created - id: 5 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_ITEM_CREATED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_ITEM_CREATED)
2021-09-29T10:02:37,786 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.783 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571210eqId] Consumer created - id: 6 subscriptionPattern: SubscriptionDefinition(eventType=DI_SRS_MARC_BIB_RECORD_MATCHED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_SRS_MARC_BIB_RECORD_MATCHED)
2021-09-29T10:02:37,859 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.857 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571284eqId] Consumer created - id: 7 subscriptionPattern: SubscriptionDefinition(eventType=DI_SRS_MARC_BIB_RECORD_NOT_MATCHED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_SRS_MARC_BIB_RECORD_NOT_MATCHED)
2021-09-29T10:02:37,921 INFO  TcpPortWaiting       Try connect to service mod-data-export-worker-1.0.7 at 10.0.2.15:9187 count 76
2021-09-29T10:02:37,980 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:37.951 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571378eqId] Consumer created - id: 8 subscriptionPattern: SubscriptionDefinition(eventType=DI_SRS_MARC_BIB_RECORD_MODIFIED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_SRS_MARC_BIB_RECORD_MODIFIED)
2021-09-29T10:02:38,045 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:38.044 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571471eqId] Consumer created - id: 9 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_INSTANCE_CREATED_READY_FOR_POST_PROCESSING, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_INSTANCE_CREATED_READY_FOR_POST_PROCESSING)
2021-09-29T10:02:38,093 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:38.092 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571519eqId] Consumer created - id: 10 subscriptionPattern: SubscriptionDefinition(eventType=DI_INVENTORY_INSTANCE_UPDATED_READY_FOR_POST_PROCESSING, subscriptionPattern=folio\.Default\.\w{1,}\.DI_INVENTORY_INSTANCE_UPDATED_READY_FOR_POST_PROCESSING)
2021-09-29T10:02:38,110 INFO  DockerModuleHandle   mod-data-export-4.0.1 2021-09-29T10:02:38,108 INFO  [vert.x-eventloop-thread-1] RestVerticle init succeeded.......
2021-09-29T10:02:38,131 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:38.129 [vert.x-worker-thread-1] INFO  KafkaConsumerWrapper [571556eqId] Consumer created - id: 11 subscriptionPattern: SubscriptionDefinition(eventType=DI_MARC_BIB_FOR_UPDATE_RECEIVED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_MARC_BIB_FOR_UPDATE_RECEIVED)
2021-09-29T10:02:38,215 INFO  DockerModuleHandle   mod-source-record-storage-5.0.5 10:02:38.214 [vert.x-eventloop-thread-1] INFO  RestVerticle         [571641eqId] init succeeded.......
2021-09-29T10:02:40,418 INFO  TcpPortWaiting       Try connect to service mod-search-1.3.1 at 10.0.2.15:9185 count 76
2021-09-29T10:02:40,606 INFO  DockerModuleHandle   mod-invoice-5.0.5 2021-09-29T10:02:40,605 INFO  [vert.x-eventloop-thread-1] LogUtil org.folio.rest.RestVerticle runPeriodicHook no periodic implementation found, continuing with deployment
2021-09-29T10:02:41,390 INFO  TcpPortWaiting       Try connect to service mod-invoice-5.0.5 at 10.0.2.15:9178 count 76
2021-09-29T10:02:42,721 INFO  DockerModuleHandle   mod-orders-12.0.3 10:02:42 [] [] [] [] INFO  LogUtil              10.0.2.15:51512 GET / null HTTP_1_1 400 28 -1 tid=null Bad Request Invalid URL path requested /
2021-09-29T10:02:42,886 INFO  TcpPortWaiting       Connected to service mod-orders-12.0.3 at 10.0.2.15:9161 count 77
2021-09-29T10:02:42,957 INFO  TcpPortWaiting       Try connect to service mod-data-export-spring-1.0.5 at 10.0.2.15:9184 count 76
2021-09-29T10:02:43,112 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.110 [vert.x-worker-thread-8] INFO  KafkaConsumerWrapper [578144eqId] Consumer created - id: 21 subscriptionPattern: SubscriptionDefinition(eventType=DI_COMPLETED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_COMPLETED)
2021-09-29T10:02:43,131 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.129 [vert.x-worker-thread-7] INFO  KafkaConsumerWrapper [578163eqId] Consumer created - id: 0 subscriptionPattern: SubscriptionDefinition(eventType=DI_COMPLETED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_COMPLETED)
2021-09-29T10:02:43,150 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.148 [vert.x-worker-thread-17] INFO  KafkaConsumerWrapper [578182eqId] Consumer created - id: 20 subscriptionPattern: SubscriptionDefinition(eventType=DI_RAW_MARC_BIB_RECORDS_CHUNK_READ, subscriptionPattern=folio\.Default\.\w{1,}\.DI_RAW_MARC_BIB_RECORDS_CHUNK_READ)
2021-09-29T10:02:43,157 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.098 [vert.x-worker-thread-14] INFO  KafkaConsumerWrapper [578132eqId] Consumer created - id: 2 subscriptionPattern: SubscriptionDefinition(eventType=DI_PARSED_RECORDS_CHUNK_SAVED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_PARSED_RECORDS_CHUNK_SAVED)
2021-09-29T10:02:43,178 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.122 [vert.x-worker-thread-15] INFO  KafkaConsumerWrapper [578156eqId] Consumer created - id: 39 subscriptionPattern: SubscriptionDefinition(eventType=DI_PARSED_RECORDS_CHUNK_SAVED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_PARSED_RECORDS_CHUNK_SAVED)
2021-09-29T10:02:43,182 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.180 [vert.x-worker-thread-16] INFO  KafkaConsumerWrapper [578214eqId] Consumer created - id: 40 subscriptionPattern: SubscriptionDefinition(eventType=DI_PARSED_RECORDS_CHUNK_SAVED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_PARSED_RECORDS_CHUNK_SAVED)
2021-09-29T10:02:43,494 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.128 [vert.x-worker-thread-18] INFO  KafkaConsumerWrapper [578162eqId] Consumer created - id: 1 subscriptionPattern: SubscriptionDefinition(eventType=DI_RAW_MARC_BIB_RECORDS_CHUNK_READ, subscriptionPattern=folio\.Default\.\w{1,}\.DI_RAW_MARC_BIB_RECORDS_CHUNK_READ)
2021-09-29T10:02:43,516 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.502 [vert.x-worker-thread-8] INFO  KafkaConsumerWrapper [578536eqId] Consumer created - id: 22 subscriptionPattern: SubscriptionDefinition(eventType=DI_ERROR, subscriptionPattern=folio\.Default\.\w{1,}\.DI_ERROR)
2021-09-29T10:02:43,519 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.517 [vert.x-worker-thread-13] INFO  KafkaConsumerWrapper [578551eqId] Consumer created - id: 43 subscriptionPattern: SubscriptionDefinition(eventType=DI_RAW_MARC_BIB_RECORDS_CHUNK_READ, subscriptionPattern=folio\.Default\.\w{1,}\.DI_RAW_MARC_BIB_RECORDS_CHUNK_READ)
2021-09-29T10:02:43,587 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.532 [vert.x-worker-thread-6] INFO  KafkaConsumerWrapper [578566eqId] Consumer created - id: 41 subscriptionPattern: SubscriptionDefinition(eventType=DI_COMPLETED, subscriptionPattern=folio\.Default\.\w{1,}\.DI_COMPLETED)
2021-09-29T10:02:43,600 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.524 [vert.x-worker-thread-7] INFO  KafkaConsumerWrapper [578558eqId] Consumer created - id: 19 subscriptionPattern: SubscriptionDefinition(eventType=DI_ERROR, subscriptionPattern=folio\.Default\.\w{1,}\.DI_ERROR)
2021-09-29T10:02:43,877 INFO  DockerModuleHandle   mod-source-record-manager-3.0.8 10:02:43.863 [vert.x-worker-thread-6] INFO  KafkaConsumerWrapper [578897eqId] Consumer created - id: 42 subscriptionPattern: SubscriptionDefinition(eventType=DI_ERROR, subscriptionPattern=folio\.Default\.\w{1,}\.DI_ERROR)
2021-09-29T10:02:44,318 INFO  TcpPortWaiting       Try connect to service mod-source-record-manager-3.0.8 at 10.0.2.15:9181 count 76
2021-09-29T10:02:44,414 INFO  TcpPortWaiting       Try connect to service mod-quick-marc-2.0.5 at 10.0.2.15:9186 count 76
2021-09-29T10:02:44,582 INFO  TcpPortWaiting       Try connect to service mod-source-record-storage-5.0.5 at 10.0.2.15:9176 count 76
2021-09-29T10:02:44,900 INFO  TcpPortWaiting       Try connect to service mod-remote-storage-1.0.5 at 10.0.2.15:9188 count 76
2021-09-29T10:02:45,292 INFO  TcpPortWaiting       Try connect to service mod-licenses-3.1.0 at 10.0.2.15:9189 count 76
2021-09-29T10:02:45,409 INFO  TcpPortWaiting       Try connect to service mod-graphql-1.8.0 at 10.0.2.15:9182 count 76
2021-09-29T10:02:45,470 INFO  TcpPortWaiting       Try connect to service mod-agreements-4.0.1 at 10.0.2.15:9190 count 76
2021-09-29T10:02:45,565 INFO  TcpPortWaiting       Try connect to service mod-data-export-4.0.1 at 10.0.2.15:9177 count 76
2021-09-29T10:02:45,851 INFO  DockerModuleHandle   mod-quick-marc-2.0.5 2021-09-29 10:02:45,751 main INFO Log4j appears to be running in a Servlet environment, but there's no log4j-web module available. If you want better web container support, please add the log4j-web JAR to your web archive or server lib directory.
2021-09-29T10:02:47,511 INFO  DockerModuleHandle   mod-kb-ebsco-java-3.7.0 10:02:47 [] [] [] [] INFO  RestVerticle         init succeeded.......
2021-09-29T10:02:48,556 INFO  TcpPortWaiting       Try connect to service mod-password-validator-2.0.2 at 10.0.2.15:9183 count 77
2021-09-29T10:02:48,848 INFO  DockerModuleHandle   mod-quick-marc-2.0.5
2021-09-29T10:02:48,849 INFO  DockerModuleHandle   mod-quick-marc-2.0.5   .   ____          _            __ _ _
2021-09-29T10:02:48,851 INFO  DockerModuleHandle   mod-quick-marc-2.0.5  /\\ / ___'_ __ _ _(_)_ __  __ _ \ \ \ \
2021-09-29T10:02:48,853 INFO  DockerModuleHandle   mod-quick-marc-2.0.5 ( ( )\___ | '_ | '_| | '_ \/ _` | \ \ \ \
2021-09-29T10:02:48,860 INFO  DockerModuleHandle   mod-quick-marc-2.0.5  \\/  ___)| |_)| | | | | || (_| |  ) ) ) )
2021-09-29T10:02:48,863 INFO  DockerModuleHandle   mod-quick-marc-2.0.5   '  |____| .__|_| |_|_| |_\__, | / / / /
2021-09-29T10:02:48,908 INFO  DockerModuleHandle   mod-quick-marc-2.0.5  =========|_|==============|___/=/_/_/_/
2021-09-29T10:02:49,120 INFO  DockerModuleHandle   mod-quick-marc-2.0.5  :: Spring Boot ::        (v2.3.4.RELEASE)
2021-09-29T10:02:49,122 INFO  DockerModuleHandle   mod-quick-marc-2.0.5
2021-09-29T10:02:49,821 INFO  DockerModuleHandle   mod-data-export-4.0.1 2021-09-29T10:02:49,756 INFO  [vert.x-eventloop-thread-1] LogUtil org.folio.rest.RestVerticle runPeriodicHook no periodic implementation found, continuing with deployment
2021-09-29T10:02:50,801 INFO  DockerModuleHandle   mod-quick-marc-2.0.5 2021-09-29 10:02:50.582 [           main] INFO  o.f.q.ModQuickMarcApplication            : No active profile set, falling back to default profiles: default
vagrant@vagrant:~$

I didn’t do anything, about 40 minutes later to refresh the page, actually can successfully log on to the system.


Now OKAPI Log output is:

vagrant@vagrant:~$ tail -100 /var/log/folio/okapi/okapi.log
2021-09-29T11:23:28,166 INFO  ProxyContext         322488/locations RES 202 215672us mod-authtoken-2.7.0 http://10.0.2.15:9152/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:28,213 INFO  ProxyContext         321351/locations REQ 172.17.0.24:44996 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,228 INFO  ProxyContext         999517/service-points REQ 172.17.0.24:44998 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,239 INFO  ProxyContext         149120/service-points RES 202 275449us mod-authtoken-2.7.0 http://10.0.2.15:9152/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:28,283 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:28,310 INFO  ProxyContext         834498/service-points RES 200 240767us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:28,321 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56298 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 165 tid=diku OK
2021-09-29T11:23:28,384 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:28,393 INFO  ProxyContext         322488/locations RES 200 226241us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:28,411 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56164 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 196 tid=diku OK
2021-09-29T11:23:28,424 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:28,430 INFO  ProxyContext         321351/locations RES 200 215810us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:28,449 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56300 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 114 tid=diku OK
2021-09-29T11:23:28,465 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:28,484 INFO  ProxyContext         999517/service-points RES 200 255268us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:28,498 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56302 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 135 tid=diku OK
2021-09-29T11:23:28,511 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:28,514 INFO  ProxyContext         149120/service-points RES 200 274439us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:28,530 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56304 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 145 tid=diku OK
2021-09-29T11:23:28,561 INFO  ProxyContext         345905/locations REQ 172.17.0.24:45008 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,563 INFO  ProxyContext         901085/service-points REQ 172.17.0.24:45010 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,565 INFO  ProxyContext         999867/locations REQ 172.17.0.24:45012 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,567 INFO  ProxyContext         204633/service-points REQ 172.17.0.24:45014 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,571 INFO  ProxyContext         800384/locations REQ 172.17.0.24:45016 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,575 INFO  ProxyContext         726673/service-points REQ 172.17.0.24:45018 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,594 INFO  ProxyContext         521493/locations REQ 172.17.0.24:45020 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,596 INFO  ProxyContext         905943/service-points REQ 172.17.0.24:45022 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,621 INFO  ProxyContext         395268/locations REQ 172.17.0.24:45026 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,643 INFO  ProxyContext         633287/service-points REQ 172.17.0.24:45028 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,645 INFO  ProxyContext         126407/locations REQ 172.17.0.24:45030 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,664 INFO  ProxyContext         549903/service-points REQ 172.17.0.24:45032 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,731 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:28,737 INFO  ProxyContext         345905/locations RES 200 176155us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:28,747 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56304 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 169 tid=diku OK
2021-09-29T11:23:28,844 INFO  ProxyContext         534792/locations REQ 172.17.0.24:45046 diku GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,846 INFO  ProxyContext         981449/service-points REQ 172.17.0.24:45048 diku GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f  mod-inventory-storage-20.2.1
2021-09-29T11:23:28,900 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:28,915 INFO  ProxyContext         901085/service-points RES 200 352173us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:28,927 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56302 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 307 tid=diku OK
2021-09-29T11:23:28,983 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:28,990 INFO  ProxyContext         999867/locations RES 200 424843us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:28,998 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:28 [] [] [] [] INFO  LogUtil              10.0.2.15:56300 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 376 tid=diku OK
2021-09-29T11:23:29,039 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,042 INFO  ProxyContext         204633/service-points RES 200 475028us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,065 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56164 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 372 tid=diku OK
2021-09-29T11:23:29,128 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:29,140 INFO  ProxyContext         800384/locations RES 200 568737us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:29,146 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56298 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 446 tid=diku OK
2021-09-29T11:23:29,164 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,165 INFO  ProxyContext         726673/service-points RES 200 589571us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,178 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56322 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 416 tid=diku OK
2021-09-29T11:23:29,181 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:29,183 INFO  ProxyContext         521493/locations RES 200 588557us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:29,196 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56332 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 427 tid=diku OK
2021-09-29T11:23:29,208 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,210 INFO  ProxyContext         905943/service-points RES 200 613503us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,213 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56334 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 419 tid=diku OK
2021-09-29T11:23:29,232 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:29,243 INFO  ProxyContext         395268/locations RES 200 620945us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:29,259 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56336 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 440 tid=diku OK
2021-09-29T11:23:29,306 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,325 INFO  ProxyContext         633287/service-points RES 200 682176us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,329 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56338 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 497 tid=diku OK
2021-09-29T11:23:29,342 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:29,347 INFO  ProxyContext         126407/locations RES 200 702560us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:29,350 INFO  DockerModuleHandle   mod-courses-1.2.2 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getCoursereservesCourses
2021-09-29T11:23:29,356 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56340 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 491 tid=diku OK
2021-09-29T11:23:29,360 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,366 INFO  ProxyContext         549903/service-points RES 200 701204us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,375 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56342 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 509 tid=diku OK
2021-09-29T11:23:29,393 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getLocationsById
2021-09-29T11:23:29,410 INFO  ProxyContext         534792/locations RES 200 565797us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/locations/53cf956f-c1df-410b-8bea-27f712cca7c0
2021-09-29T11:23:29,421 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56304 GET /locations/53cf956f-c1df-410b-8bea-27f712cca7c0 null HTTP_1_1 200 572 478 tid=diku OK
2021-09-29T11:23:29,437 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getServicePointsByServicepointId
2021-09-29T11:23:29,449 INFO  ProxyContext         981449/service-points RES 200 602063us mod-inventory-storage-20.2.1 http://10.0.2.15:9160/service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f
2021-09-29T11:23:29,456 INFO  DockerModuleHandle   mod-inventory-storage-20.2.1 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:56348 GET /service-points/3a40852d-49fd-4df2-a1f9-6e2641a6e91f null HTTP_1_1 200 411 491 tid=diku OK
2021-09-29T11:23:29,486 INFO  ProxyContext         559830/coursereserves RES 200 7389727us mod-courses-1.2.2 http://10.0.2.15:9145/coursereserves/courses?limit=100
2021-09-29T11:23:29,499 INFO  DockerModuleHandle   mod-courses-1.2.2 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:35460 GET /coursereserves/courses limit=100 HTTP_1_1 200 14394 7308 tid=diku OK
2021-09-29T11:23:29,515 INFO  DockerModuleHandle   mod-courses-1.2.2 11:23:29 [] [] [] [] INFO  LogUtil              org.folio.rest.RestVerticle start  invoking getCoursereservesCourses
2021-09-29T11:23:29,522 INFO  ProxyContext         533212/coursereserves RES 200 6709507us mod-courses-1.2.2 http://10.0.2.15:9145/coursereserves/courses?limit=100&query=%28cql.allRecords%3D1%29%20sortby%20name
2021-09-29T11:23:29,529 INFO  DockerModuleHandle   mod-courses-1.2.2 11:23:29 [] [] [] [] INFO  LogUtil              10.0.2.15:35476 GET /coursereserves/courses limit=100&query=%28cql.allRecords%3D1%29%20sortby%20name HTTP_1_1 200 14394 4079 tid=diku OK
vagrant@vagrant:~$

Is it a long time before a service program starts?

Thanks,
Kai

julianladisch
29 Sep '21

Depending on the hardware it may take many minutes until all modules have started.

kzeng
29 Sep '21

Oh, I see. Thank you very much.