search

aFuerst / openwhisk-caching

Apache License 2.0
9 stars 2 forks source link

wsk always timeout (HTTP status code 504) when accessing 172.17.0.1:3233 #1

Open CodingYuanLiu opened 3 years ago

CodingYuanLiu commented 3 years ago

Hello Alexander, Sorry to bother you. Hope I'm allowed to get some advice on openwhisk-standalone deployment. Thank you!

I just follow the steps described in section A.3.2 of the ASPLOS paper. However, my wsk can not access openwhisk-standalone after setting the apihost to 172.17.0.1:3233. Any request will cause a timeout and returns:

error: xxx: The connection failed, or timed out. (HTTP status code 504)

For example, a wsk action list command will cause:

error: Unable to obtain the list of actions for namespace 'default': The connection failed, or timed out. (HTTP status code 504)

But my ./run.sh of Openwhisk does not produce any error.

Environment details:

* ubuntu 18.04
* openjdk version "1.8.0_292"

Steps to reproduce the issue:

  1. Edit the sample-app.conf and put it in ./bin/. Rename it to application.conf
  2. Modify the last line of run.sh from sudo env "PATH=/home/alfuerst/repos/wsk-cli" /usr/bin/java -jar ./bin/openwhisk-standalone.jar --dev-mode --ui-port 7894 -c ./bin/application.conf to sudo /usr/bin/java -jar ./bin/openwhisk-standalone.jar --dev-mode --ui-port 7894 -c ./bin/application.conf
  3. Run run.sh, and the openwhisk starts.
  4. Run wsk command to openwhisk.

Provide the actual results and outputs:

The output of openwhisk standalone is as follows:

                                                                                                           [170/719]        ____      ___                   _    _ _     _     _
       /\   \    / _ \ _ __   ___ _ __ | |  | | |__ (_)___| | __                                                      /\  /__\   \  | | | | '_ \ / _ \ '_ \| |  | | '_ \| / __| |/ /
 /  \____ \  /  | |_| | |_) |  __/ | | | |/\| | | | | \__ \   <                                                      \   \  /  \/    \___/| .__/ \___|_| |_|__/\__|_| |_|_|___/_|\_\
  \___\/ tm           |_|

Git Commit: 38ff898, Build Date: 2020-12-15T02:38:37+0800                                                           [2021-07-21T10:43:14.621Z] [INFO] Starting OpenWhisk standalone on port 3233                                        [2021-07-21T10:43:16.792Z] [INFO] Slf4jLogger started
[2021-07-21T10:43:16.995Z] [INFO] Using [/root/.openwhisk/standalone/server-3233] as data directory
[2021-07-21T10:43:17.244Z] [INFO] [#tid_sid_unknown] [StandaloneDockerClient] Detected docker client version 20.10.5[2021-07-21T10:43:17.312Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient] running /usr/bin/docker ps --quiet
--no-trunc --all --filter name=whisk- (timeout: 1 minute) [marker:invoker_docker.ps_start:8]
[2021-07-21T10:43:17.473Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient]  [marker:invoker_docker.ps_finish:170:35]
[2021-07-21T10:43:17.504Z] [INFO] [#tid_sid_standalone] [StandaloneDockerSupport] removing 0 containers launched for Standalone server support.
================================================================================
Launched service details
                                                                                                                    [ 3233  ] http://172.17.0.1:3233 (Controller)
[ 7894  ] http://172.17.0.1:7894/playground (Playground)
                                                                                                                    Local working directory - /root/.openwhisk/standalone/server-3233
================================================================================
[2021-07-21T10:43:18.312Z] [INFO] [#tid_sid_unknown] [MemoryArtifactStore] Created MemoryStore for [subjects]
[2021-07-21T10:43:18.333Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: default' [marker:database_getDocument_start:83]
[2021-07-21T10:43:18.334Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_getDocument_finish:84:1]
[2021-07-21T10:43:18.352Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: default, rev: null' [marker:database_saveDocument_start:102]
[2021-07-21T10:43:18.355Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_saveDocument_finish:105:3]
[2021-07-21T10:43:18.355Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [default]
[2021-07-21T10:43:18.383Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: guest' [marker:database_getDocument_start:19]                                                                            [2021-07-21T10:43:18.383Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_getDocument_finish:19:0]
[2021-07-21T10:43:18.385Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: guest, rev: null' [marker:database_saveDocument_start:21]
[2021-07-21T10:43:18.385Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_saveDocument_finish:21:0]
[2021-07-21T10:43:18.385Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [guest]
[2021-07-21T10:43:18.411Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: whisk.system' [marker:database_getDocument_start:18]
[2021-07-21T10:43:18.411Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_getDocument_finish:18:0]
[2021-07-21T10:43:18.413Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: whisk.system, rev: null' [marker:database_saveDocument_start:20]
[2021-07-21T10:43:18.413Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore]  [marker:database_saveDocument_finish:20:0]
[2021-07-21T10:43:18.414Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [whisk.system]
[2021-07-21T10:43:18.475Z] [WARN] Failed to attach the instrumentation because the Kamon Bundle is not present on the classpath
[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.triggers.fires.perMinute
[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.sequence.maxLength[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.invokes.concurrent[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for whisk.api.host.name              [2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.invokes.perMinute
[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for whisk.api.host.proto
[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for whisk.api.host.port              [2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for runtimes.manifest
[2021-07-21T10:43:18.626Z] [INFO] [#tid_sid_unknown] [Config] system set value for port
[2021-07-21T10:43:18.666Z] [INFO] [#tid_sid_unknown] [LeanMessagingProvider] topic completedstandalone created      [2021-07-21T10:43:18.666Z] [INFO] [#tid_sid_unknown] [LeanMessagingProvider] topic health created
[2021-07-21T10:43:18.666Z] [INFO] [#tid_sid_unknown] [LeanMessagingProvider] topic cacheInvalidation created        [2021-07-21T10:43:18.666Z] [INFO] [#tid_sid_unknown] [LeanMessagingProvider] topic events created
[2021-07-21T10:43:18.723Z] [INFO] [#tid_sid_controller] [Controller] starting controller instance standalone [marker:controller_startup_counter:1482]
[2021-07-21T10:43:18.781Z] [INFO] [#tid_sid_unknown] [MemoryArtifactStore] Created MemoryStore for [whisks]         [2021-07-21T10:43:18.794Z] [INFO] [#tid_sid_dispatcher] [MessageFeed] handler capacity = 128, pipeline fill at = 128, pipeline depth = 256
[2021-07-21T10:43:18.824Z] [INFO] [#tid_sid_dispatcher] [MessageFeed] handler capacity = 128, pipeline fill at = 128, pipeline depth = 256
[2021-07-21T10:43:18.998Z] [INFO] [#tid_sid_unknown] [ExtendedDockerClient] Detected docker client version 20.10.5
[2021-07-21T10:43:18.999Z] [INFO] [#tid_sid_unknown] [InvokerReactive] LogStoreProvider: class org.apache.openwhisk.core.containerpool.docker.DockerCliLogStore                                                                         [2021-07-21T10:43:19.062Z] [INFO] [#tid_sid_unknown] [DockerClientWithFileAccess] Detected docker client version 20.10.5
[2021-07-21T10:43:19.196Z] [INFO] [#tid_sid_unknown] [StandaloneDockerContainerFactory] Standalone docker container factory config pullStandardImages: false
[2021-07-21T10:43:19.197Z] [INFO] [#tid_sid_invoker] [DockerClientWithFileAccess] running /usr/bin/docker ps --quiet --no-trunc --all --filter name=wsk0_ (timeout: 1 minute) [marker:invoker_docker.ps_start:1956]
[2021-07-21T10:43:19.233Z] [INFO] [#tid_sid_invoker] [DockerClientWithFileAccess]  [marker:invoker_docker.ps_finish:1992:36]                                                                                                            [2021-07-21T10:43:19.234Z] [INFO] [#tid_sid_invoker] [StandaloneDockerContainerFactory] removing 0 action containers.                                                                                                                   [2021-07-21T10:43:19.332Z] [INFO] [#tid_sid_unknown] [MemoryArtifactStore] Created MemoryStore for [activations]
[2021-07-21T10:43:19.374Z] [INFO] [#tid_sid_invoker] [MemoryArtifactStore] [QUERY] 'subjects' searching 'namespaceThrottlings/blockedNamespaces [marker:database_queryView_start:2133]
[2021-07-21T10:43:19.380Z] [INFO] [#tid_sid_invoker] [MemoryArtifactStore]  [marker:database_queryView_finish:2139:6]
[2021-07-21T10:43:19.381Z] [INFO] [#tid_sid_unknown] [InvokerReactive] updated blacklist to 0 entries
[2021-07-21T10:43:19.416Z] [INFO] [#tid_sid_dispatcher] [MessageFeed] handler capacity = 32768, pipeline fill at = 32768, pipeline depth = 65536
[2021-07-21T10:43:19.440Z] [INFO] [#tid_sid_controller] [Controller] loadbalancer initialized: LeanBalancer
[2021-07-21T10:43:19.467Z] [INFO] [#tid_sid_controller] [KindRestrictor] all kinds are allowed, the white-list is not specified                                                                                                         [2021-07-21T10:43:19.548Z] [INFO] [#tid_sid_unknown] [ExtendedDockerClient] Detected docker client version 20.10.5
[2021-07-21T10:43:20.017Z] [INFO] [#tid_sid_controller] [ActionsApi] actionSequenceLimit '50'
Waiting for OpenWhisk server at http://172.17.0.1:3233 to start since 1.075 ms                                      [2021-07-21T10:43:20.225Z] [INFO] [#tid_a2uKOerK7yHSplBjuncAyUGHIbGL5C9q] GET /                                     [2021-07-21T10:43:20.293Z] [INFO] [#tid_a2uKOerK7yHSplBjuncAyUGHIbGL5C9q] [BasicHttpService] [marker:http_get.200_counter:72:72]
[2021-07-21T10:43:20.382Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] PUT /api/v1/namespaces/_/actions/playground-delete overwrite=true                                                                                             [2021-07-21T10:43:20.401Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [BasicAuthenticationDirective] authenticate: 789c46b1-71f6-4ed5-8c54-816aa4f8c502
[2021-07-21T10:43:20.453Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [Identity] [GET] serving from datastore: CacheKey(789c46b1-71f6-4ed5-8c54-816aa4f8c502) [marker:database_cacheMiss_counter:72]
[2021-07-21T10:43:20.453Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore] [QUERY] 'subjects' searching 'subjects.v2.0.0/identities [marker:database_queryView_start:72]
[2021-07-21T10:43:20.458Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore] [GET] 'subjects' finding document: 'whisk.system/limits' [marker:database_getDocument_start:76]
[2021-07-21T10:43:20.458Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore]  [marker:database_getDocument_finish:77:1]                                                                                              [2021-07-21T10:43:20.461Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore]  [marker:database_queryView_finish:80:8]                                                                                                [2021-07-21T10:43:20.533Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [WhiskAction] [GET] serving from datastore: CacheKey(whisk.system/playground-delete) [marker:database_cacheMiss_counter:152]
[2021-07-21T10:43:20.534Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore] [GET] 'whisks' finding document: 'id: whisk.system/playground-delete' [marker:database_getDocument_start:152]                           [2021-07-21T10:43:20.534Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore]  [marker:database_getDocument_finish:153:1]
[2021-07-21T10:43:20.534Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [WhiskAction] invalidating CacheKey(whisk.system/playground-delete)
[2021-07-21T10:43:20.593Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [WhiskAction] write initiated on new cache entry
[2021-07-21T10:43:20.616Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore] [PUT] 'whisks' saving document: 'id: whisk.system/playground-delete, rev: null' [marker:database_saveDocument_start:235]                [2021-07-21T10:43:20.617Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [MemoryArtifactStore]  [marker:database_saveDocument_finish:236:1]                                                                                            [2021-07-21T10:43:20.622Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [WhiskAction] write all done, caching CacheKey(whisk.system/playground-delete) Cached                                                                         [2021-07-21T10:43:20.640Z] [INFO] [#tid_ODWrUdyp6zEQhkSWnEh0VBxACY6n3MQ0] [BasicHttpService] [marker:http_put.200_counter:259:259]                                                                                                      [2021-07-21T10:43:20.657Z] [INFO] [#tid_sid_playground] [PlaygroundLauncher] Installed action playground-delete
[2021-07-21T10:43:20.660Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] PUT /api/v1/namespaces/_/actions/playground-fetch overwrite=true                                                                                              [2021-07-21T10:43:20.662Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [BasicAuthenticationDirective] authentica$
d-fetch overwrite=true                                                                                      [35/719]
[2021-07-21T10:43:20.662Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [BasicAuthenticationDirective] authenticate: 789c46b1-71f6-4ed5-8c54-816aa4f8c502
[2021-07-21T10:43:20.665Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [Identity] [GET] serving from cache: CacheKey(789c46b1-71f6-4ed5-8c54-816aa4f8c502) [marker:database_cacheHit_counter:4]                                      [2021-07-21T10:43:20.668Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [WhiskAction] [GET] serving from datastore: CacheKey(whisk.system/playground-fetch) [marker:database_cacheMiss_counter:7]
[2021-07-21T10:43:20.668Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [MemoryArtifactStore] [GET] 'whisks' finding document: 'id: whisk.system/playground-fetch' [marker:database_getDocument_start:8]                              [2021-07-21T10:43:20.668Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [MemoryArtifactStore]  [marker:database_getDocument_finish:8:0]
[2021-07-21T10:43:20.668Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [WhiskAction] invalidating CacheKey(whisk.system/playground-fetch)                                                                                            [2021-07-21T10:43:20.669Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [WhiskAction] write initiated on new cache entry
[2021-07-21T10:43:20.676Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [MemoryArtifactStore] [PUT] 'whisks' saving document: 'id: whisk.system/playground-fetch, rev: null' [marker:database_saveDocument_start:16]
[2021-07-21T10:43:20.677Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [MemoryArtifactStore]  [marker:database_saveDocument_finish:17:1]
[2021-07-21T10:43:20.677Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [WhiskAction] write all done, caching CacheKey(whisk.system/playground-fetch) Cached
[2021-07-21T10:43:20.679Z] [INFO] [#tid_CN5zrEKCUnivE074dR5NNeumX1bMGNMI] [BasicHttpService] [marker:http_put.200_counter:19:19]                                                                                                        [2021-07-21T10:43:20.682Z] [INFO] [#tid_sid_playground] [PlaygroundLauncher] Installed action playground-fetch      [2021-07-21T10:43:20.686Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] PUT /api/v1/namespaces/_/actions/playground-run overwrite=true                                                                                                [2021-07-21T10:43:20.687Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [BasicAuthenticationDirective] authenticate: 789c46b1-71f6-4ed5-8c54-816aa4f8c502                                                                             [2021-07-21T10:43:20.687Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [Identity] [GET] serving from cache: CacheKey(789c46b1-71f6-4ed5-8c54-816aa4f8c502) [marker:database_cacheHit_counter:2]                                      [2021-07-21T10:43:20.690Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] [GET] serving from datastore: CacheKey(whisk.system/playground-run) [marker:database_cacheMiss_counter:5]                                       [2021-07-21T10:43:20.690Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore] [GET] 'whisks' finding document: 'id: whisk.system/playground-run' [marker:database_getDocument_start:5]                                [2021-07-21T10:43:20.690Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore]  [marker:database_getDocument_finish:5:0]                                                                                               [2021-07-21T10:43:20.690Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] invalidating CacheKey(whisk.system/playground-run)                                                                                              [2021-07-21T10:43:20.691Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] write initiated on new cache entry                                                                                                              [2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore] [PUT] 'whisks' saving document: 'id: whisk.system/playground-run, rev: null' [marker:database_saveDocument_start:12]                    [2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore]  [marker:database_saveDocument_finish:12:0]
[2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] write all done, caching CacheKey(whisk.system/playground-run) Cached                                                                            [2021-07-21T10:43:20.699Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [BasicHttpService] [marker:http_put.200_c$
tDocument_finish:5:0]                                                                                        [0/719]
[2021-07-21T10:43:20.690Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] invalidating CacheKey(whisk.system/playground-run)
[2021-07-21T10:43:20.691Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] write initiated on new cache
 entry                                                                                                              [2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore] [PUT] 'whisks' saving document: 'id: whisk.system/playground-run, rev: null' [marker:database_saveDocument_start:12]
[2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [MemoryArtifactStore]  [marker:database_saveDocument_finish:12:0]                                                                                             [2021-07-21T10:43:20.697Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [WhiskAction] write all done, caching CacheKey(whisk.system/playground-run) Cached
[2021-07-21T10:43:20.699Z] [INFO] [#tid_uF9U0VxyAWcpVTpAteFcBxVa9NcGvKkd] [BasicHttpService] [marker:http_put.200_counter:14:14]                                                                                                        [2021-07-21T10:43:20.702Z] [INFO] [#tid_sid_playground] [PlaygroundLauncher] Installed action playground-run        [2021-07-21T10:43:20.706Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] PUT /api/v1/namespaces/_/actions/playground-userpackage overwrite=true                                                                                        [2021-07-21T10:43:20.707Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [BasicAuthenticationDirective] authenticate: 789c46b1-71f6-4ed5-8c54-816aa4f8c502                                                                             [2021-07-21T10:43:20.707Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [Identity] [GET] serving from cache: CacheKey(789c46b1-71f6-4ed5-8c54-816aa4f8c502) [marker:database_cacheHit_counter:1]                                      [2021-07-21T10:43:20.710Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [WhiskAction] [GET] serving from datastore: CacheKey(whisk.system/playground-userpackage) [marker:database_cacheMiss_counter:4]                               [2021-07-21T10:43:20.710Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [MemoryArtifactStore] [GET] 'whisks' finding document: 'id: whisk.system/playground-userpackage' [marker:database_getDocument_start:4]                        [2021-07-21T10:43:20.710Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [MemoryArtifactStore]  [marker:database_getDocument_finish:4:0]
[2021-07-21T10:43:20.710Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [WhiskAction] invalidating CacheKey(whisk.system/playground-userpackage)                                                                                      [2021-07-21T10:43:20.711Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [WhiskAction] write initiated on new cache
 entry                                                                                                              [2021-07-21T10:43:20.716Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [MemoryArtifactStore] [PUT] 'whisks' saving document: 'id: whisk.system/playground-userpackage, rev: null' [marker:database_saveDocument_start:10]            [2021-07-21T10:43:20.716Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [MemoryArtifactStore]  [marker:database_saveDocument_finish:10:0]                                                                                             [2021-07-21T10:43:20.716Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [WhiskAction] write all done, caching CacheKey(whisk.system/playground-userpackage) Cached                                                                    [2021-07-21T10:43:20.718Z] [INFO] [#tid_iqoKs5A0MGs9za8ekUJWs3qJYcpTSu5j] [BasicHttpService] [marker:http_put.200_counter:12:12]                                                                                                        [2021-07-21T10:43:20.721Z] [INFO] [#tid_sid_playground] [PlaygroundLauncher] Installed action playground-userpackage
[2021-07-21T10:43:20.724Z] [WARN] [#tid_sid_playground] [PlaygroundLauncher] Failed to launch browser java.io.IOException: Cannot run program "xdg-open": error=2, No such file or directory                                            [2021-07-21T10:48:19.395Z] [INFO] [#tid_sid_invoker] [MemoryArtifactStore] [QUERY] 'subjects' searching 'namespaceThrottlings/blockedNamespaces [marker:database_queryView_start:302154]                                                [2021-07-21T10:48:19.395Z] [INFO] [#tid_sid_invoker] [MemoryArtifactStore]  [marker:database_queryView_finish:302154:0]                                                                                                                 [2021-07-21T10:48:19.395Z] [INFO] [#tid_sid_unknown] [InvokerReactive] updated blacklist to 0 entries
GitHubDiom commented 3 years ago

try wsk -i action list

aFuerst commented 3 years ago

@CodingYuanLiu verify the port information matches anything in application.conf. And possibly try using localhost instead of that loopback IP

IULeen commented 2 years ago

Hello Alexander, Sorry to bother you, too. I also met two problems when I run the openwhisk-standalone.jar, and I sincerely hope I can get your help.

Environmental details

Steps to reproduce the issue:

  1. Build openwhisk-standalone.jar by ./gradlew :core:standalone:build
  2. Move sample-app.conf and put it in ./bin/. Rename it to application.conf
  3. Add customruntime.json in ./bin/. Its content is the same as ansible/file/runtime.json
  4. Launch openwhisk-standalone.jar via cmd java -jar openwhisk-standalone.jar --dev-mode -c application.conf -m customruntime.json

My Problems

  1. When I launch openwhisk-standalone.jar without option --kafka, there will be an ERROR that prevented the program from running. So I wonder that how I can omit the --kafka option to launch openwhisk-standalone.jar.

    
root@master:/home/openfaas/openwhisk-caching/bin# java -jar openwhisk-standalone.jar -c application.conf -m customruntime.json --dev-mode

    ____      ___                   _    _ _     _     _
   /\   \    / _ \ _ __   ___ _ __ | |  | | |__ (_)___| | __
/\  /__\   \  | | | | '_ \ / _ \ '_ \| |  | | '_ \| / __| |/ /
/  \____ \  /  | |_| | |_) |  __/ | | | |/\| | | | | \__ \   <
\   \  /  \/    \___/| .__/ \___|_| |_|__/\__|_| |_|_|___/_|\_\
\___\/ tm           |_|

Git Commit: 3b8a26f, Build Date: 2021-09-16T13:06:48+0000 [2022-04-14T12:02:05.801Z] [INFO] Starting OpenWhisk standalone on port 3233 [2022-04-14T12:02:07.185Z] [INFO] Slf4jLogger started [2022-04-14T12:02:07.408Z] [INFO] Using [/root/.openwhisk/standalone/server-3233] as data directory [2022-04-14T12:02:07.859Z] [INFO] [#tid_sid_unknown] [StandaloneDockerClient] Detected docker client version 20.10.7 [2022-04-14T12:02:07.995Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient] running /usr/bin/docker ps --quiet --no-trunc --all --filter name=whisk- (timeout: 1 minute) [marker:invoker_docker.ps_start:8] [2022-04-14T12:02:08.184Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient] [marker:invoker_docker.ps_finish:197:41] [2022-04-14T12:02:08.213Z] [INFO] [#tid_sid_standalone] [StandaloneDockerSupport] removing 0 containers launched for Standalone server support.

Launched service details

[ 3233 ] http://172.17.0.1:3233 (Controller) [ 3232 ] http://172.17.0.1:3232/playground (Playground)

Local working directory - /root/.openwhisk/standalone/server-3233

[2022-04-14T12:02:09.398Z] [INFO] [#tid_sid_unknown] [MemoryArtifactStore] Created MemoryStore for [subjects] [2022-04-14T12:02:09.426Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: guest' [marker:database_getDocument_start:173] [2022-04-14T12:02:09.427Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_getDocument_finish:174:0] [2022-04-14T12:02:09.453Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: guest, rev: null' [marker:database_saveDocument_start:200] [2022-04-14T12:02:09.457Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_saveDocument_finish:204:3] [2022-04-14T12:02:09.464Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [guest] [2022-04-14T12:02:09.549Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: alftest' [marker:database_getDocument_start:68] [2022-04-14T12:02:09.550Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_getDocument_finish:68:0] [2022-04-14T12:02:09.553Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: alftest, rev: null' [marker:database_saveDocument_start:72] [2022-04-14T12:02:09.555Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_saveDocument_finish:73:0] [2022-04-14T12:02:09.555Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [alftest] [2022-04-14T12:02:09.622Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [GET] 'subjects' finding document: 'id: whisk.system' [marker:database_getDocument_start:49] [2022-04-14T12:02:09.623Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_getDocument_finish:49:0] [2022-04-14T12:02:09.626Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [PUT] 'subjects' saving document: 'id: whisk.system, rev: null' [marker:database_saveDocument_start:53] [2022-04-14T12:02:09.628Z] [INFO] [#tid_sid_cli] [MemoryArtifactStore] [marker:database_saveDocument_finish:55:0] [2022-04-14T12:02:09.629Z] [INFO] [#tid_sid_userBootstrap] [StandaloneOpenWhisk] Created user [whisk.system] [2022-04-14T12:02:09.852Z] [WARN] Failed to attach the instrumentation because the Kamon Bundle is not present on the classpath [2022-04-14T12:02:10.020Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.triggers.fires.perMinute [2022-04-14T12:02:10.020Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.sequence.maxLength [2022-04-14T12:02:10.021Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.invokes.concurrent [2022-04-14T12:02:10.021Z] [INFO] [#tid_sid_unknown] [Config] system set value for limits.actions.invokes.perMinute [2022-04-14T12:02:10.021Z] [INFO] [#tid_sid_unknown] [Config] system set value for runtimes.manifest [2022-04-14T12:02:10.022Z] [INFO] [#tid_sid_unknown] [Config] system set value for port

[2022-04-14T12:02:10.054Z] [ERROR] [#tid_sid_unknown] [Config] required property kafka.hosts still not set [2022-04-14T12:02:10.055Z] [ERROR] [#tid_sid_unknown] [Controller] Bad configuration, cannot start.

[2022-04-14T12:02:10.060Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient] running /usr/bin/docker ps --quiet --no-trunc --all --filter name=whisk- (timeout: 1 minute) [marker:invoker_docker.ps_start:1] [2022-04-14T12:02:10.113Z] [INFO] [#tid_sid_standalone] [StandaloneDockerClient] [marker:invoker_docker.ps_finish:54:53] [2022-04-14T12:02:10.116Z] [INFO] [#tid_sid_standalone] [StandaloneDockerSupport] removing 0 containers launched for Standalone server support. [2022-04-14T12:02:10.123Z] [INFO] [#tid_sid_unknown] [Controller] Shutting down Kamon with coordinated shutdown


2. When launching openwhisk-standalone with the `--kafka` option, it will successfully run without shutdown. But when I invoke  functions that created by `wsk_inteaction.py`, there is another error. 
**What should I do to deal the error of `java.lang.ArithmeticException: / by zero`**
#### Action list and Action invoke

root@master:/home/master# wsk action list actions /alftest/json private python:3 /alftest/video private python:ai-vid /alftest/aes private python:3 /alftest/train private python:ai /alftest/lin_pack private python:ai /alftest/image private python:ai /alftest/hello private python:3 /alftest/gzip private python:3 /alftest/float private python:ai /alftest/dd private python:3 /alftest/cnn private python:ai /alftest/cham private python:3 root@master:/home/master# wsk action invoke hello --result error: Unable to invoke action 'hello': The connection failed, or timed out. (HTTP status code 500) Run 'wsk --help' for usage.


#### openwhisk-standalone.jar's error infomation

[2022-04-14T12:47:45.382Z] [INFO] [#tidWGr0vEuRaS8yVIpcIucSTKx1VdJU6hm9] POST /api/v1/namespaces//actions/hello blocking=true&result=true [2022-04-14T12:47:45.385Z] [INFO] [#tid_WGr0vEuRaS8yVIpcIucSTKx1VdJU6hm9] [Identity] [GET] serving from cache: CacheKey(babecafe-cafe-babe-cafe-babecafebabe) [marker:database_cacheHit_counter:2] [2022-04-14T12:47:45.385Z] [ERROR] Error during processing of request: '/ by zero'. Completing with 500 Internal Server Error response. To change default exception handling behavior, provide a custom ExceptionHandler. java.lang.ArithmeticException: / by zero at org.apache.openwhisk.core.entitlement.EntitlementProvider.calculateIndividualLimit(Entitlement.scala:131) at org.apache.openwhisk.core.entitlement.EntitlementProvider.$anonfun$invokeRateThrottler$2(Entitlement.scala:138) at org.apache.openwhisk.core.entitlement.EntitlementProvider.$anonfun$invokeRateThrottler$2$adapted(Entitlement.scala:138) at org.apache.openwhisk.core.entitlement.RateThrottler.check(RateThrottler.scala:50) at org.apache.openwhisk.core.entitlement.EntitlementProvider.checkUserThrottle(Entitlement.scala:367) at org.apache.openwhisk.core.entitlement.EntitlementProvider.checkThrottles(Entitlement.scala:214) at org.apache.openwhisk.core.entitlement.EntitlementProvider.check(Entitlement.scala:300) at org.apache.openwhisk.core.entitlement.EntitlementProvider.check(Entitlement.scala:264) at org.apache.openwhisk.core.controller.BasicAuthorizedRouteProvider.$anonfun$authorizeAndDispatch$1(AuthorizedRouteDispatcher.scala:75) at akka.http.scaladsl.server.directives.FutureDirectives.$anonfun$onComplete$2(FutureDirectives.scala:37) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRequestContext$2(BasicDirectives.scala:45) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRouteResult$2(BasicDirectives.scala:68) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRouteResult$2(BasicDirectives.scala:68) at akka.http.scaladsl.server.Directive.$anonfun$recover$5(Directive.scala:126) at akka.http.scaladsl.util.FastFuture$.strictTransform$1(FastFuture.scala:41) at akka.http.scaladsl.util.FastFuture$.transformWith$extension1(FastFuture.scala:45) at akka.http.scaladsl.util.FastFuture$.flatMap$extension(FastFuture.scala:26) at akka.http.scaladsl.server.Directive.$anonfun$recover$2(Directive.scala:125) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRequestContext$2(BasicDirectives.scala:45) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$2(RouteConcatenation.scala:47) at akka.http.scaladsl.util.FastFuture$.strictTransform$1(FastFuture.scala:41) at akka.http.scaladsl.util.FastFuture$.transformWith$extension1(FastFuture.scala:45) at akka.http.scaladsl.util.FastFuture$.flatMap$extension(FastFuture.scala:26) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRequestContext$2(BasicDirectives.scala:45) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$mapRequestContext$2(BasicDirectives.scala:45) at akka.http.scaladsl.server.directives.BasicDirectives.$anonfun$textract$2(BasicDirectives.scala:161) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$2(RouteConcatenation.scala:47) at akka.http.scaladsl.util.FastFuture$.strictTransform$1(FastFuture.scala:41) at akka.http.scaladsl.util.FastFuture$.transformWith$extension1(FastFuture.scala:45) at akka.http.scaladsl.util.FastFuture$.flatMap$extension(FastFuture.scala:26) at akka.http.scaladsl.server.RouteConcatenation$RouteWithConcatenation.$anonfun$$tilde$1(RouteConcatenation.scala:44) at akka.http.scaladsl.server.directives.OnSuccessMagnet$$anon$1.$anonfun$directive$3(FutureDirectives.scala:97) at akka.http.scaladsl.util.FastFuture$.strictTransform$1(FastFuture.scala:41) at akka.http.scaladsl.util.FastFuture$.$anonfun$transformWith$3(FastFuture.scala:51) at scala.concurrent.impl.CallbackRunnable.run(Promise.scala:64) at akka.dispatch.BatchingExecutor$AbstractBatch.processBatch(BatchingExecutor.scala:56) at akka.dispatch.BatchingExecutor$BlockableBatch.$anonfun$run$1(BatchingExecutor.scala:93) at scala.runtime.java8.JFunction0$mcV$sp.apply(JFunction0$mcV$sp.java:23) at scala.concurrent.BlockContext$.withBlockContext(BlockContext.scala:85) at akka.dispatch.BatchingExecutor$BlockableBatch.run(BatchingExecutor.scala:93) at akka.dispatch.TaskInvocation.run(AbstractDispatcher.scala:48) at akka.dispatch.ForkJoinExecutorConfigurator$AkkaForkJoinTask.exec(ForkJoinExecutorConfigurator.scala:48) at java.base/java.util.concurrent.ForkJoinTask.doExec(ForkJoinTask.java:290) at java.base/java.util.concurrent.ForkJoinPool$WorkQueue.topLevelExec(ForkJoinPool.java:1020) at java.base/java.util.concurrent.ForkJoinPool.scan(ForkJoinPool.java:1656) at java.base/java.util.concurrent.ForkJoinPool.runWorker(ForkJoinPool.java:1594) at java.base/java.util.concurrent.ForkJoinWorkerThread.run(ForkJoinWorkerThread.java:183)

aFuerst commented 2 years ago

This error has nothing to do with kafka. The exception ends up in checkUserThrottle and invokeRateThrottler. I don't know what those functions exactly do, but form the names I suspect they're related to the function invocation rate throttling. Perhaps there's something wrong with your limits.firesPerMinute setting in the conf file, or another of the options in the limit group located there