Portworx on Openshift 4.5

Hi, I’m tring to install Portworx on OpenShift 4.5.18 using operator.
After the installation I got:

NAME READY STATUS RESTARTS AGE IP NODE NOMINATED NODE READINESS GATES
px-cluster-dd9c44e1-b379-4334-b4d6-1cd3c3fb0f5f-8rqh6 2/2 Running 0 97m 10.64.22.134 ccr-svil-dc2hv-worker-westeurope3-hkrgv
px-cluster-dd9c44e1-b379-4334-b4d6-1cd3c3fb0f5f-9mwtz 2/2 Running 0 97m 10.64.22.135 ccr-svil-dc2hv-worker-westeurope1-rtzdg
px-cluster-dd9c44e1-b379-4334-b4d6-1cd3c3fb0f5f-bzjv9 2/2 Running 0 97m 10.64.22.138 ccr-svil-dc2hv-worker-westeurope2-vvg5z
px-cluster-dd9c44e1-b379-4334-b4d6-1cd3c3fb0f5f-cd58v 2/2 Running 0 97m 10.64.22.137 ccr-svil-dc2hv-worker-westeurope2-gvcj7
px-cluster-dd9c44e1-b379-4334-b4d6-1cd3c3fb0f5f-l5nj6 1/2 Running 1 21m 10.64.22.133 ccr-svil-dc2hv-worker-westeurope1-k559k

One of five container doesn’t start correctly.
In this pod I have this log:
[…]
2020-11-18T11:42:14.440705097+00:00 stderr F W1118 11:42:14.440629 1 connection.go:170] Still connecting to unix:///csi/csi.sock
2020-11-18T11:42:24.440664716+00:00 stderr F W1118 11:42:24.440603 1 connection.go:170] Still connecting to unix:///csi/csi.sock
[…]
2020-11-18T11:35:08.786712665+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“runC spec unchanged”
2020-11-18T11:35:08.786712665+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“Portworx service restart not required.”
2020-11-18T11:35:08.786734166+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“Activating node-watcher”
2020-11-18T11:35:08.790360103+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“Portworx service is ACTIVE”
2020-11-18T11:35:08.790386405+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“REST: Changing install-state: ST_INSTALL -> ST_FINISH”
2020-11-18T11:35:08.790386405+00:00 stderr F time=“2020-11-18T11:35:08Z” level=info msg=“Start tailing portworx.service logs”
2020-11-18T11:35:09.798853517+00:00 stderr F time=“2020-11-18T11:35:09Z” level=info msg="> Starting local log-tailer"
2020-11-18T11:35:09.801459185+00:00 stderr F time=“2020-11-18T11:35:09Z” level=info msg="> run-local: /px-log-tail --follow -P @ -tf -u portworx.service -u portworx-output.service -u init.scope -n 20000 -p 1888551"
2020-11-18T11:35:09.801459185+00:00 stderr F time=“2020-11-18T11:35:09Z” level=info msg=“Install done - MAIN exiting”
2020-11-18T11:35:09.805423240+00:00 stderr F time=“2020-11-18T11:35:09Z” level=info msg="-- Flushing logs for PID 1888551 [20000 lines] --"
2020-11-18T11:35:09.809596109+00:00 stderr F time=“2020-11-18T11:35:09Z” level=info msg="-- Start tailing the logs for portworx.service, portworx-output.service, init.scope --"
2020-11-18T11:35:09.810633376+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k systemd[1]: Started libcontainer container 8a41879d7e153b4977cbb6e23dc4eda7a746e2d4769a22fb4e65b95a20cf5bc3.
2020-11-18T11:35:15.696656168+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:35:15Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:35:25.946493589+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:35:25Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:35:35.696630792+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:35:35Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:35:45.696487733+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:35:45Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:35:55.696640566+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:35:55Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:36:05.696582518+00:00 stdout F @ccr-svil-dc2hv-worker-westeurope1-k559k portworx[1888528]: time=“2020-11-18T11:36:05Z” level=warning msg=“503 Node status not OK (STATUS_INIT)” Driver=“Cluster API” ID=nodeHealth Request=“Cluster API”
2020-11-18T11:36:15.595361569+00:00 stderr F time=“2020-11-18T11:36:15Z” level=warning msg=“Could not retrieve PX node status” error=“Node status not OK (STATUS_INIT)\n”
I also tried to delete pod but the problem is still present.

How to resolve this?
Thanks

The output from the pods themselves will not be sufficient - these are monitoring components and the Portworx software has to get to a certain point in order for the pod’s collection of logs to be functional. This appears to not yet be the case here.

Can you instead gather the output of journalctl -au portworx* from one of the nodes themselves please? Then make a snippet on a pastebin and provide a link here - You will need to log in as either root (if using EL8) or the core user and sudo to root if using RHCOS (the latter of which we’ve seen the most number of issues with due to the rate at which the kernel version changes (PX has support for only certain versions).

Hi thanks for your response.
This is the output from node with the problem:

$ cat /etc/redhat-release
Red Hat Enterprise Linux CoreOS release 4.5

$ uname -a
Linux ccr-svil-dc2hv-worker-westeurope3-hkrgv 4.18.0-193.28.1.el8_2.x86_64 #1 SMP Fri Oct 16 13:38:49 EDT 2020 x86_64 x86_64 x86_64 GNU/Linux

$ journalctl -au portworx*

Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Rootfs found at /var/opt/pwx/oci>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="PX binaries found at /var/opt/pw>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Multipath conf update disabled. >
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Initializing as version 2.6.1.4->
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="SPEC READ [93407b9d9195a8b61bdc2>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg=“Enabling Sharedv4 NFS support …>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg=“Setting up NFS service”
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg=”> Initialized service controls v>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Checking mountpoints for followi>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Found following mountpoints for >
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="PX-RunC arguments: -b -c px-clus>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="PX-RunC mounts: /dev:/dev /etc/e>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="PX-RunC env: CONTAINER_RUNTIME=c>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Parent OCI mount '/var/opt/pwx/o>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg="Found 2 usable runc binaries: /v>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg=“Detected kernel release 4.18.0-1>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=info msg=“Exec: [”/var/opt/pwx/bin/runc”>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Executing with arguments: -b -c px-cluster-23888d1b-9ce3-49eb-b95c-93e707b59>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Installed pxctl…
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Tue Nov 17 16:24:29 UTC 2020 : Running version 2.6.1.4-775a586 on Linux ccr->
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Version: Linux version 4.18.0-193.28.1.el8_2.x86_64 (mockbuild@x86-vm-07.bui>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: mapping:
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Setting portmap: 9001
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:29Z” level=error msg="Cannot listen on UNIX socket: l>
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Error: failed to listen on pxd.sock
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: sed: can’t read /etc/mdadm/mdadm.conf: No such file or directory
Nov 17 16:24:29 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Skipping reassembly as no px array config found
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: PXD version: 228c7b5119cd6cddc0c79e3c3bc23eac5ca8d4c9
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Checking fs version…
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Module version check: Success
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Done checking fs version…
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Using cluster: px-cluster-23888d1b-9ce3-49eb-b95c-93e707b5951b
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Using journal device: auto
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Using kvdb device: type=Premium_LRS,size=150
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Warning skipping device exists check for: type=Premium_LRS,size=150.
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Port range start: 17001
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Using storage device: type=Standard_LRS,size=1000
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Using scheduler: kubernetes
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Clearing lttng tmpfs location: /var/lib/osd/lttng…
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: ******************************************************************
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: ****** Checking mdraid0 layout path for null **************
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: ******************************************************************
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: patch_fs already done
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Checking sysfs mount…
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: sysfs on /sys/firmware type sysfs (ro,relatime,seclabel)
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: sysfs mounted read-only. remounting…
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: mapping:
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Setting portmap: 17001
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: “bootstrap”: true,
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:30,680 CRIT Supervisor running as root (no user in config f>
Nov 17 16:24:30 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:30,686 INFO supervisord started with pid 1
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,689 INFO spawned: ‘reboot-diags’ with pid 253
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,691 INFO spawned: ‘px-nfs’ with pid 254
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,694 INFO spawned: ‘relayd’ with pid 255
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,695 INFO spawned: ‘cron’ with pid 256
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,697 INFO spawned: ‘px-etcd’ with pid 257
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17

16:24:31,698 INFO spawned: ‘lttng’ with pid 258
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,699 INFO spawned: ‘exec’ with pid 259
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,701 INFO spawned: ‘cache_flush’ with pid 262
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,703 INFO spawned: ‘px-diag’ with pid 268
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,705 INFO spawned: ‘px-healthmon’ with pid 274
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,707 INFO spawned: ‘pxdaemon’ with pid 276
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,709 INFO spawned: ‘px-ns’ with pid 277
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,712 INFO spawned: ‘px_event_listener’ with pid 285
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,713 INFO exited: reboot-diags (exit status 0; expected)
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,713 INFO exited: px-nfs (exit status 0; expected)
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:31,713 INFO exited: cache_flush (exit status 0; expected)
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Tracefile cleanup: Tracing disabled, remove all previous traces…
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Clean out lttng tmpfs location: …
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:31Z” level=info msg=“px-ns Starting…”
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:31Z” level=info msg="InitPxClient No authentication e>
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Installed NS trace handler for SIGHUP
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Installed NS sig-handler for SIGUSR1
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Installed NS sig-handler for SIGUSR2
Nov 17 16:24:31 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Starting NS server
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,747 INFO success: relayd entered RUNNING state, process >
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: cron entered RUNNING state, process ha>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: px-etcd entered RUNNING state, process>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: lttng entered RUNNING state, process h>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: exec entered RUNNING state, process ha>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: px-diag entered RUNNING state, process>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: px-healthmon entered RUNNING state, pr>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: px-ns entered RUNNING state, process h>
Nov 17 16:24:32 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:32,748 INFO success: px_event_listener entered RUNNING stat>
Nov 17 16:24:36 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:24:36,745 INFO success: pxdaemon entered RUNNING state, proces>
Nov 17 16:24:36 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: Tracing is disabled, not starting trace processes.
Nov 17 16:24:37 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: PXPROCS[INFO]: Started px-storage with pid 344
Nov 17 16:24:37 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: bash: connect: Connection refused
Nov 17 16:24:37 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: bash: /dev/tcp/localhost/17006: Connection refused
Nov 17 16:24:37 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: PXPROCS[INFO]: px-storage not started yet…sleeping
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: PXPROCS[INFO]: Started px with pid 355
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: PXPROCS[INFO]: Started watchdog with pid 356
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17_16:24:40: PX-Watchdog: Starting watcher
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17_16:24:40: PX-Watchdog: Waiting for px process to start
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17_16:24:40: PX-Watchdog: (pid 355): Begin monitoring
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg="Registering [kernel] as a volume>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg="Registered the Usage based Meter>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg=“Setting log level to info(4)”
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=error msg="Cannot listen on UNIX socket: l>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=warning msg="Failed to start pxd-dummy: fa>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg=“read config from env var” func=i>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg=“read config from config.json” fu>
Nov 17 16:24:40 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:40Z” level=info msg="Alerts initialized successfully >
Nov 17 16:24:41 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:41Z” level=info msg="Could not find locally attached >
Nov 17 16:24:42 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:42Z” level=info msg="Trying to attach available Drive>
Nov 17 16:24:42 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:42Z” level=warning msg="Failed to attach DriveSet (6e>
Nov 17 16:24:42 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:42Z” level=warning msg="Failed to find locally attach>
Nov 17 16:24:42 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:42Z” level=info msg=“Node is not yet initialized” fun>
Nov 17 16:24:42 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:42Z” level=info msg="Generated a new NodeID: 12eb504d>
Nov 17 16:24:53 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:53Z” level=info msg="Created drive PX-DO-NOT-DELETE-5>
Nov 17 16:24:53 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:24:53Z” level=info msg="Successfully created a Drive Set>
Nov 17 16:25:03 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:03Z” level=info msg="Attached drive PX-DO-NOT-DELETE->
Nov 17 16:25:03 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:03Z” level=info msg=“drive is now attached” Drive ID=>
Nov 17 16:25:03 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:03Z” level=info msg="Successfully attached the Drive >
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Using GW interface device:[eth0]>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Detected Machine Hardware Type a>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Bootstrapping internal kvdb serv>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Registered auditor for kvdb-resp>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Registered auditor for kvdb-limi>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“created kv instance” func=initKv>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Setting lock timeout to: 3m0s”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“creating kvdb metrics wrapper”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“initialized internal kvdb” func=>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“initialized osdconfig manager” f>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“pushed config data to kvdb” func>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Starting PX Version: 2.6.1.4-775>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Using GW interface device:[eth0]>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Found the following shared mount>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Node 12eb504d-b99c-43a6-982f-e99>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Initializing scheduler hook: kub>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Configured PX Scheduler filter f>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Configured PX Scheduler integrat>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="node previously initialized:fals>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Joining cluster px-cluster-23888>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX Configuration Loaded…”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="PX Cluster ID: px-cluster-23888d>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="PX Node ID: 12eb504d-b99c-43a6-9>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX Node Index: 0”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="PX Management Iface: "
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX Discovery Server(s): []”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="PX Storage Type: Devices: [type>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="PX Node Cache Function Attribute>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Detected hardware type as: Virtu>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Initializing licensing”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="No Trial/Enterprise licenses ins>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Licensing engine initialized usi>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license Nodes{count:5,exp>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license Volumes{count:500>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license VolumeSize{count:>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license NodeCapacity{coun>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license NodeCapacityExten>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license Snapshots{count:5>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license LocalVolumeAttach>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license AggregatedVolume{>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license SharedVolume{coun>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license ScaledVolume{coun>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license EncryptedVolume{c>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license GlobalSecretsOnly>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license ResizeVolume{coun>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license SnapshotToObjectS>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license SnapshotToObjectS>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license CloudMigration{co>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license DisasterRecovery{>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license AUTCapacityManage>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Parsed license OIDCSecurity{coun>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Parsed license EnablePlatformBar>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Parsed license EnablePlatformVM{>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Parsed license HaLevel{count:3,e>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Global license watcher installed>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX-Essential license configured >
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Attempting Secrets Login to Kube>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX starting cluster manager…”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX cluster manager running.”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Adding cluster event listener: S>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Converting VolumeSpecs to SdkSto>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX starting storage…”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Adding cluster event listener: P>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“SDK TLS disabled” name=SDK-tcp
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“SDK-tcp gRPC Server ready on [::>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“SDK TLS disabled” name=SDK-unix
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“SDK-unix gRPC Server ready on /v>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“SDK gRPC REST Gateway started on>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Setting concurrent API limit: 20”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Starting server on port: :17001”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“PX API server running on port 17>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Starting API Server with TLS Dis>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Starting Watchdog server.”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Adding cluster event listener: K>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Cluster manager starting…”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“initializing osdconfig manager”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Cluster state is OK… Joining t>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Node 12eb504d-b99c-43a6-982f-e99>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Cluster ID: px-cluster-23888d1b->
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Node Mgmt IP: 10.64.22.133”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Node Data IP: 10.64.22.133”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Node HWType: VirtualMachine”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=“Service Provider information…”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tISP: Microsoft Corporation”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tIP: 20.56.53.116”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tCountry: Netherlands”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tCity: Amsterdam”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tZIP: 1011”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tRegion: NH”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tLat: 52.3667”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tLng: 4.9”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg=”\tTimezone: Europe/Amsterdam”
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=warning msg="PX-CACHE: cache_blksize param>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="merged option: stats_num_detach_>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="merged option: rpc_timeout_sec=1>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="merged option: execution_timeout>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Authentication with Kubernetes S>
Nov 17 16:25:04 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:04Z” level=info msg="Detected provider Azure, Geo {Az>
Nov 17 16:25:05 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:05Z” level=info msg=“Made 1 pools”
Nov 17 16:25:05 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:05Z” level=info msg="Partitioning data drive /dev/sdc>
Nov 17 16:25:05 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:05Z” level=info msg="Waiting for partition table upda>
Nov 17 16:25:07 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:07Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:25:11 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:11Z” level=error msg="Device: /dev/sdc: Failed in Ma>
Nov 17 16:25:12 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:12Z” level=info msg="Using journal block device /dev/>
Nov 17 16:25:12 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:12Z” level=info msg="Detected provider Azure, Geo {Az>
Nov 17 16:25:14 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:14Z” level=info msg="Partitioned /dev/sdc into journa>
Nov 17 16:25:14 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:14Z” level=info msg=“Benchmarking drive /dev/sdc2”
Nov 17 16:25:15 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:15Z” level=info msg="Updated the current set of kvdb >
Nov 17 16:25:17 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:17Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:25:24 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:24Z” level=info msg="Storage pool WriteThroughput 22>
Nov 17 16:25:25 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:25Z” level=info msg=“Initializing journal: /dev/sdc1”
Nov 17 16:25:27 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:27Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:25:37 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:37Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:25:47 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:47Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:25:47 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:47Z” level=info msg="Mounting metadata pool: Cos:LOW >
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="HAL:Created volume:/var/.px/0/.r>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="HAL:Created volume:/var/.px/0/.m>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg=“Applying labels to Pool 0”
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="Sync pxpool=0,mdpoolid=0,initinp>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="Created Metadata Volume: /var/.p>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="Node 12eb504d-b99c-43a6-982f-e99>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=info msg="This node participates in quorum>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=error msg="Failed finalizing init (can nod>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=error msg="Failed to save the database. Un>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=warning msg="Cleaning up new NodeID’s iden>
Nov 17 16:25:48 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:48Z” level=error msg="Failed to remove kvdb node from>
Nov 17 16:25:51 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:25:51,715 INFO exited: px-etcd (exit status 1; not expected)
Nov 17 16:25:52 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:25:52,718 INFO spawned: ‘px-etcd’ with pid 561
Nov 17 16:25:53 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: 2020-11-17 16:25:53,733 INFO success: px-etcd entered RUNNING state, process>
Nov 17 16:25:57 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:25:57Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:26:07 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:26:07Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:26:09 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:26:09Z” level=info msg="Detached drive PX-DO-NOT-DELETE->
Nov 17 16:26:15 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:26:15Z” level=info msg="Deleted drive PX-DO-NOT-DELETE-5>
Nov 17 16:26:15 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:26:15Z” level=info msg="Updating node statuses to find a>
Nov 17 16:26:17 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]: time=“2020-11-17T16:26:17Z” level=warning msg="503 Node status not OK (STA>
Nov 17 16:26:27 ccr-svil-dc2hv-worker-westeurope1-k559k portworx[29509]:

It will be difficult to post this in this forum as this log is likely very long, can you please utilize a pastebin service (or a gist on github) and post a link to the paste instead?

Sure:

Thanks in advance
Vincenzo Marzario

Hi Vincenzo,

I just had a look at the pastebin link, but it also looks truncated/cut off short, on that pastebin - can you re-capture it from the node, or perhaps a different node if the issue affects more than one node?

In the one you provided, Portworx is still initializing and between the first message to last is only a span of two minutes - Portworx typically needs anywhere from 2-5 minutes to initialize/install the first time (depending on the environment and configuration).

Hi Aleks,
this is the log from another node:

Thanks
Vincenzo Marzario

I had a look, the other node’s logs just show it regularly updating its essentials license hourly (as expected) and no errors. Though it’s hard to tell, the other node appears that Portworx would be functioning normally (although as no other messages are seen, it may just be idle and not doing anything just yet). Can you run pxctl status on that other node and let us know what you see? Also ideally we’d want to capture diagnostics from any node with an issue and gather these files, the procedure for that is: pxctl sv diags -a -f (run on each node) and the generated file will be in /var/cores/diags.tar.gz - you may need to post links to a file sharing service as these are binary files and may not be compatible with pastebins (and this forum only allows images to be posted)

Hi Aleks,
thanks for your support.
We have a deathline, so I must reinstall the cluster using Wipe as strategy.
Now, the new cluster works fine.

The problem was I initially deployed 8 worker node, then I scaled down to 5 nodes.
But whithouth wiping.
I launched pxctl status on a new node and I saw:
Status: PX is operational License: PX-Essential (ERROR: License is expired, Another cluster registered with the same userID. Unlink the previously registered cluster on PX-Central.)

So I decided to reinstall.

Best regards
Vincenzo Marzario

Glad you were able to sort out the cluster. We limit the number of Portworx Essential clusters to exactly one per PX-Central user, so if you try to set up a second cluster, the first cluster’s “license” may be invalidated (or vice versa). If you really needed another cluster, there’s little to stop you from creating a different user on px-central with another email, but we don’t advocate that as a proper solution (those limitations are there as we prefer people upgrade to Portworx Enterprise if they need unhindered flexibility (and the support there is 24x7x365 via Jira rather than a forum that is largely intended to be community provided (as the Essentials product is relatively new, we still have engineers keep an eye here from time to time but it is not as high a priority to do so). Thanks for understanding and if you need anything further (such as more info on Enterprise), please ask on here.