[ERR] Timed out after 1m0s: health check never passed.#1119

 

[ERR] Timed out after 1m0s: health check never passed.#1119

 Closed avirkar opened this issue on 14 Apr 2017 · 3 comments

 Closed

[ERR] Timed out after 1m0s: health check never passed. #1119

avirkar opened this Issue on 14 Apr 2017 · 3 comments

Comments

Assignees

No one assigned

Labels

None yet

Projects

None yet

Milestone

No milestone

3 participants

@avirkar

@cf-gitbot

@dkoper

@avirkar

avirkar commented on 14 Apr 2017

I am trying to deploy a docker image on cloudfoundry, but I am getting healthcheck errors. The image works perfectly fine on my local but gives error when I push using cloudfoundry cli.

 

2017-04-13T15:29:19.822-04:00 [API/6] [OUT] Created app with guid 831aeb36-1e7d-4bd1-a4f9-a57d345ea549

2017-04-13T15:29:21.103-04:00 [API/5] [OUT] Updated app with guid 831aeb36-1e7d-4bd1-a4f9-a57d345ea549 ({"route"=>"48f18823-45f4-4d85-9ea2-5d8c410bf8ef"})

2017-04-13T15:29:25.054-04:00 [API/0] [OUT] Updated app with guid 831aeb36-1e7d-4bd1-a4f9-a57d345ea549 ({"state"=>"STARTED"})

2017-04-13T15:29:30.154-04:00 [STG/0] [OUT] Creating container

2017-04-13T15:29:30.481-04:00 [STG/0] [OUT] Successfully created container

2017-04-13T15:29:30.482-04:00 [STG/0] [OUT] Staging...

2017-04-13T15:29:30.573-04:00 [STG/0] [OUT] Staging process started ...

2017-04-13T15:29:32.479-04:00 [STG/0] [OUT] Staging process finished

2017-04-13T15:29:32.488-04:00 [STG/0] [OUT] Staging Complete

2017-04-13T15:29:32.488-04:00 [STG/0] [OUT] Exit status 0

2017-04-13T15:29:34.175-04:00 [STG/0] [OUT] Destroying container

2017-04-13T15:29:34.575-04:00 [STG/0] [OUT] Successfully destroyed container

2017-04-13T15:29:45.351-04:00 [CELL/0] [OUT] Creating container

2017-04-13T15:32:15.528-04:00 [CELL/0] [OUT] Successfully created container

2017-04-13T15:32:15.529-04:00 [CELL/0] [OUT] Starting health monitoring of container

2017-04-13T15:32:16.216-04:00 [APP/0] [OUT] Detected Tomcat server.

2017-04-13T15:32:16.217-04:00 [APP/0] [OUT] Nuxeo home: /opt/nuxeo/server

2017-04-13T15:32:16.217-04:00 [APP/0] [OUT] Nuxeo configuration: /opt/nuxeo/server/bin/nuxeo.conf

2017-04-13T15:32:16.247-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/common-base

2017-04-13T15:32:16.248-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/default

2017-04-13T15:32:16.248-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/common

2017-04-13T15:32:16.544-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/4a20ab58964ad2a83ee4278df456793b

2017-04-13T15:32:16.917-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/f74404db187e4f2ed4dcb0b4041d6c1d

2017-04-13T15:32:17.509-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/303a6a96f3b0f8612ea54ce95df28aa5

2017-04-13T15:32:17.535-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/bd31cd502972487faf50aecc18a2aed2

2017-04-13T15:32:17.607-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/9d92822910db0e0f6c8f060e10f6dab4

2017-04-13T15:32:17.623-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/6f98a1891068b5144c03e85fcadd079a

2017-04-13T15:32:17.658-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/939c56a289c34ca4ab737d2d34e88125

2017-04-13T15:32:17.920-04:00 [APP/0] [OUT] Added /opt/nuxeo/server/setupWizardDownloads/e4741619df48c0036925fee404043c3f

2017-04-13T15:32:17.944-04:00 [APP/0] [OUT] /docker-entrypoint.sh: ignoring /docker-entrypoint-initnuxeo.d/*

2017-04-13T15:32:18.551-04:00 [APP/0] [OUT] Detected Tomcat server.

2017-04-13T15:32:18.553-04:00 [APP/0] [OUT] Nuxeo configuration: /opt/nuxeo/server/bin/nuxeo.conf

2017-04-13T15:32:18.553-04:00 [APP/0] [OUT] Nuxeo home: /opt/nuxeo/server

2017-04-13T15:32:18.589-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/common

2017-04-13T15:32:18.589-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/common-base

2017-04-13T15:32:18.590-04:00 [APP/0] [OUT] Include template: /opt/nuxeo/server/templates/default

2017-04-13T15:32:18.877-04:00 [APP/0] [OUT] No current configuration, generating files...

2017-04-13T15:32:19.302-04:00 [APP/0] [OUT] Configuration files generated.

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] 19:32:20,239 INFO [Http11Protocol] Initializing ProtocolHandler ["http-bio-0.0.0.0-8080"]

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] # Running Nuxeo Preprocessor ...

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] 19:32:20,275 INFO [StandardEngine] Starting Servlet Engine: Apache Tomcat/7.0.69

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] 19:32:20,248 INFO [AjpProtocol] Initializing ProtocolHandler ["ajp-bio-0.0.0.0-8009"]

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] 19:32:20,250 INFO [Catalina] Initialization processed in 423 ms

2017-04-13T15:32:20.314-04:00 [APP/0] [OUT] 19:32:20,274 INFO [StandardService] Starting service Catalina

2017-04-13T15:32:20.318-04:00 [APP/0] [OUT] Server started with process ID 259.

2017-04-13T15:32:20.319-04:00 [APP/0] [OUT] Go to http://localhost:8080/nuxeo

2017-04-13T15:32:20.474-04:00 [APP/0] [OUT] Preprocessing: /opt/nuxeo/server/nxserver

2017-04-13T15:32:21.006-04:00 [APP/0] [OUT] Done.

2017-04-13T15:32:21.006-04:00 [APP/0] [OUT] # Preprocessing done.

2017-04-13T15:32:21.032-04:00 [APP/0] [OUT] 19:32:21,031 INFO [HostConfig] Deploying configuration descriptor /opt/nuxeo/server/conf/Catalina/localhost/nuxeo.xml

2017-04-13T15:32:21.672-04:00 [APP/0] [OUT] 19:32:21,669 INFO [TldConfig] At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] ======================================================================

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] 19:32:21,846 INFO [FrameworkLoader]

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Configuration Directory = /opt/nuxeo/server/nxserver/config

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Server home = /opt/nuxeo/server

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] = Starting Nuxeo Framework

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Temp Directory = /opt/nuxeo/server/tmp

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Data Directory = /var/lib/nuxeo/data

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Runtime home = /opt/nuxeo/server/nxserver

2017-04-13T15:32:21.846-04:00 [APP/0] [OUT] * Log Directory = /var/log/nuxeo

2017-04-13T15:32:21.847-04:00 [APP/0] [OUT] ======================================================================

2017-04-13T15:32:22.303-04:00 [APP/0] [OUT] 19:32:22,302 WARN [ComponentManagerImpl] Component org.nuxeo.runtime.trackers.files.threadstracking.config was blacklisted. Ignoring.

2017-04-13T15:32:23.596-04:00 [APP/0] [OUT] 19:32:23,596 INFO [ElasticSearchComponent] Registering local embedded configuration: EsLocalConfig(nuxeoCluster, /var/lib/nuxeo/data/elasticsearch, true, mmapfs), loaded from service:org.nuxeo.elasticsearch.defaultConfig

2017-04-13T15:32:23.598-04:00 [APP/0] [OUT] 19:32:23,598 INFO [ElasticSearchComponent] Registering index configuration: EsIndexConfig(nuxeo, default, doc), loaded from service:org.nuxeo.elasticsearch.defaultConfig

2017-04-13T15:32:23.599-04:00 [APP/0] [OUT] 19:32:23,599 INFO [ElasticSearchComponent] Registering index configuration: EsIndexConfig(nuxeo-uidgen, null, seqId), loaded from service:org.nuxeo.elasticsearch.index.sequence.contrib

2017-04-13T15:32:23.599-04:00 [APP/0] [OUT] 19:32:23,598 INFO [ElasticSearchComponent] Registering index configuration: EsIndexConfig(nuxeo-audit, null, entry), loaded from service:org.nuxeo.elasticsearch.index.audit.contrib

2017-04-13T15:32:26.415-04:00 [APP/0] [OUT] 19:32:26,414 WARN [ComponentManagerImpl] Component org.nuxeo.runtime.trackers.files.threadstracking.config was blacklisted. Ignoring.

2017-04-13T15:32:29.609-04:00 [APP/0] [OUT] 19:32:29,609 WARN [ElasticSearchAdminImpl] Elasticsearch embedded configuration is ONLY for testing purpose. You need to create a dedicated Elasticsearch cluster for production.

2017-04-13T15:32:29.859-04:00 [APP/0] [OUT] 19:32:29,859 WARN [env] [nuxeoNode] max file descriptors [16384] for elasticsearch process likely too low, consider increasing to at least [65536]

2017-04-13T15:32:33.553-04:00 [APP/0] [OUT] 19:32:33,553 INFO [ESAuditBackend] Activate Elasticsearch backend for Audit

2017-04-13T15:32:35.125-04:00 [APP/0] [OUT] 19:32:35,125 INFO [AutomationComponent] You can activate automation trace mode to get more informations on automation executions

2017-04-13T15:32:35.535-04:00 [APP/0] [ERR] INFO: Jod will be using /usr/lib/libreoffice

2017-04-13T15:32:35.535-04:00 [APP/0] [ERR] Apr 13, 2017 7:32:35 PM org.artofsolving.jodconverter.util.PlatformUtils searchExistingfile

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - expired_search: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - default_search: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - default_document_suggestion: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - domain_documents: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] 19:32:35,552 INFO [PageProviderClassReplacerRegistry] List of page provider names that are superseded:

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - DEFAULT_DOCUMENT_SUGGESTION: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:35.553-04:00 [APP/0] [OUT] - advanced_document_content: org.nuxeo.elasticsearch.provider.ElasticSearchNxqlPageProvider

2017-04-13T15:32:42.181-04:00 [APP/0] [OUT] ======================================================================

2017-04-13T15:32:42.181-04:00 [APP/0] [OUT] 19:32:42,181 INFO [OSGiRuntimeService] Nuxeo Platform Started

2017-04-13T15:32:42.181-04:00 [APP/0] [OUT] = Component Loading Status: Pending: 0 / Missing: 0 / Unstarted: 0 / Total: 437

2017-04-13T15:32:42.544-04:00 [APP/0] [OUT] 19:32:42,544 INFO [HostConfig] Deploying web application directory /opt/nuxeo/server/webapps/ROOT

2017-04-13T15:32:42.544-04:00 [APP/0] [OUT] 19:32:42,543 INFO [HostConfig] Deployment of configuration descriptor /opt/nuxeo/server/conf/Catalina/localhost/nuxeo.xml has finished in 21,511 ms

2017-04-13T15:32:43.033-04:00 [APP/0] [OUT] 19:32:43,033 INFO [TldConfig] At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.

2017-04-13T15:32:43.037-04:00 [APP/0] [OUT] 19:32:43,037 INFO [HostConfig] Deployment of web application directory /opt/nuxeo/server/webapps/ROOT has finished in 493 ms

2017-04-13T15:32:43.037-04:00 [APP/0] [OUT] 19:32:43,037 INFO [HostConfig] Deploying web application directory /opt/nuxeo/server/webapps/manager

2017-04-13T15:32:43.265-04:00 [APP/0] [OUT] 19:32:43,265 INFO [TldConfig] At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.

2017-04-13T15:32:43.273-04:00 [APP/0] [OUT] 19:32:43,272 INFO [HostConfig] Deployment of web application directory /opt/nuxeo/server/webapps/manager has finished in 235 ms

2017-04-13T15:32:43.273-04:00 [APP/0] [OUT] 19:32:43,272 INFO [HostConfig] Deploying web application directory /opt/nuxeo/server/webapps/host-manager

2017-04-13T15:32:43.607-04:00 [APP/0] [OUT] 19:32:43,606 INFO [TldConfig] At least one JAR was scanned for TLDs yet contained no TLDs. Enable debug logging for this logger for a complete list of JARs that were scanned but no TLDs were found in them. Skipping unneeded JARs during scanning can improve startup time and JSP compilation time.

2017-04-13T15:32:43.610-04:00 [APP/0] [OUT] 19:32:43,610 INFO [HostConfig] Deployment of web application directory /opt/nuxeo/server/webapps/host-manager has finished in 338 ms

2017-04-13T15:32:43.613-04:00 [APP/0] [OUT] 19:32:43,612 INFO [Http11Protocol] Starting ProtocolHandler ["http-bio-0.0.0.0-8080"]

2017-04-13T15:32:43.625-04:00 [APP/0] [OUT] 19:32:43,625 INFO [AjpProtocol] Starting ProtocolHandler ["ajp-bio-0.0.0.0-8009"]

2017-04-13T15:32:43.626-04:00 [APP/0] [OUT] 19:32:43,625 INFO [Catalina] Server startup in 23375 ms

 

2017-04-13T15:33:17.439-04:00 [CELL/0] [ERR] Timed out after 1m0s: health check never passed.

2017-04-13T15:33:17.442-04:00 [CELL/0] [OUT] Exit status 0

 

@cf-gitbot

Collaborator

cf-gitbot commented on 14 Apr 2017

We have created an issue in Pivotal Tracker to manage this:

 

https://www.pivotaltracker.com/story/show/143686777

 

The labels on this github issue will be updated when the story is started.

 

@cf-gitbot cf-gitbot added the unscheduled label on 14 Apr 2017

@dkoper

Contributor

dkoper commented on 14 Apr 2017

Have you tried with a different health check type, e.g. cf push yourapp -u process?

https://docs.cloudfoundry.org/devguide/deploy-apps/healthchecks.html

https://docs.cloudfoundry.org/devguide/deploy-apps/troubleshoot-app-health.html#start