{"level":"warn","ts":"2026-08-26T00:12:28.425945Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:12:28.427957Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.144:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.144:2380","--initial-cluster=instance=https://162.253.55.144:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.144:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.144:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-08-26T00:12:28.42988Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-26T00:12:28.429896Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-08-26T00:12:28.429925Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:12:28.430936Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"]} {"level":"info","ts":"2026-08-26T00:12:28.431047Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.144:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-08-26T00:12:28.435176Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.868578ms"} {"level":"info","ts":"2026-08-26T00:12:28.441134Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"873be3731fd7a3eb","cluster-id":"9ca43c00a45c1826"} {"level":"info","ts":"2026-08-26T00:12:28.441274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=()"} {"level":"info","ts":"2026-08-26T00:12:28.441348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 0"} {"level":"info","ts":"2026-08-26T00:12:28.441419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 873be3731fd7a3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-26T00:12:28.441466Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became follower at term 1"} {"level":"info","ts":"2026-08-26T00:12:28.441626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"warn","ts":"2026-08-26T00:12:28.448597Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-26T00:12:28.450058Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-26T00:12:28.451367Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-08-26T00:12:28.45431Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"873be3731fd7a3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-26T00:12:28.454487Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"873be3731fd7a3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-26T00:12:28.454665Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:12:28.454693Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:12:28.4547Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-08-26T00:12:28.456364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb switched to configuration voters=(9744632302341170155)"} {"level":"info","ts":"2026-08-26T00:12:28.456593Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","added-peer-id":"873be3731fd7a3eb","added-peer-peer-urls":["https://162.253.55.144:2380"]} {"level":"info","ts":"2026-08-26T00:12:28.461749Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-08-26T00:12:28.461852Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-08-26T00:12:28.461909Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.144:2380"} {"level":"info","ts":"2026-08-26T00:12:28.462094Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"873be3731fd7a3eb","initial-advertise-peer-urls":["https://162.253.55.144:2380"],"listen-peer-urls":["https://162.253.55.144:2380"],"advertise-client-urls":["https://162.253.55.144:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.144:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-26T00:12:28.462157Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-26T00:12:29.042943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb is starting a new election at term 1"} {"level":"info","ts":"2026-08-26T00:12:29.043004Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-08-26T00:12:29.043028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgPreVoteResp from 873be3731fd7a3eb at term 1"} {"level":"info","ts":"2026-08-26T00:12:29.043048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became candidate at term 2"} {"level":"info","ts":"2026-08-26T00:12:29.043059Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb received MsgVoteResp from 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-08-26T00:12:29.043084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"873be3731fd7a3eb became leader at term 2"} {"level":"info","ts":"2026-08-26T00:12:29.043097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 873be3731fd7a3eb elected leader 873be3731fd7a3eb at term 2"} {"level":"info","ts":"2026-08-26T00:12:29.044252Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"873be3731fd7a3eb","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.144:2379]}","request-path":"/0/members/873be3731fd7a3eb/attributes","cluster-id":"9ca43c00a45c1826","publish-timeout":"7s"} {"level":"info","ts":"2026-08-26T00:12:29.044325Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:29.044699Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:12:29.044725Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-26T00:12:29.045696Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-26T00:12:29.045766Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-26T00:12:29.049128Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-26T00:12:29.050492Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ca43c00a45c1826","local-member-id":"873be3731fd7a3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:29.050646Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:29.0507Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-26T00:12:29.051378Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.144:2379"} {"level":"info","ts":"2026-08-26T00:12:56.974451Z","caller":"traceutil/trace.go:171","msg":"trace[43998004] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:442; }","duration":"174.062658ms","start":"2026-08-26T00:12:56.800366Z","end":"2026-08-26T00:12:56.974428Z","steps":["trace[43998004] 'read index received' (duration: 173.875891ms)","trace[43998004] 'applied index is now lower than readState.Index' (duration: 185.867µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:12:56.97452Z","caller":"traceutil/trace.go:171","msg":"trace[1830602566] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"174.955651ms","start":"2026-08-26T00:12:56.799533Z","end":"2026-08-26T00:12:56.974489Z","steps":["trace[1830602566] 'process raft request' (duration: 174.774834ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:12:56.974632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.226665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-08-26T00:12:56.974706Z","caller":"traceutil/trace.go:171","msg":"trace[2127925370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:429; }","duration":"174.354504ms","start":"2026-08-26T00:12:56.80034Z","end":"2026-08-26T00:12:56.974695Z","steps":["trace[2127925370] 'agreement among raft nodes before linearized reading' (duration: 174.219735ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.523154Z","caller":"traceutil/trace.go:171","msg":"trace[316771596] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"199.613086ms","start":"2026-08-26T00:13:04.323512Z","end":"2026-08-26T00:13:04.523125Z","steps":["trace[316771596] 'process raft request' (duration: 199.440369ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.590938Z","caller":"traceutil/trace.go:171","msg":"trace[504299972] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"261.704034ms","start":"2026-08-26T00:13:04.329199Z","end":"2026-08-26T00:13:04.590903Z","steps":["trace[504299972] 'process raft request' (duration: 261.474288ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591289Z","caller":"traceutil/trace.go:171","msg":"trace[1668109905] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"254.787443ms","start":"2026-08-26T00:13:04.336486Z","end":"2026-08-26T00:13:04.591273Z","steps":["trace[1668109905] 'process raft request' (duration: 254.604436ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.590965Z","caller":"traceutil/trace.go:171","msg":"trace[75735981] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"261.611687ms","start":"2026-08-26T00:13:04.329327Z","end":"2026-08-26T00:13:04.590938Z","steps":["trace[75735981] 'process raft request' (duration: 261.485639ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591276Z","caller":"traceutil/trace.go:171","msg":"trace[265694354] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"254.789473ms","start":"2026-08-26T00:13:04.33643Z","end":"2026-08-26T00:13:04.59122Z","steps":["trace[265694354] 'process raft request' (duration: 254.536177ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591035Z","caller":"traceutil/trace.go:171","msg":"trace[1493274509] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"258.555469ms","start":"2026-08-26T00:13:04.332464Z","end":"2026-08-26T00:13:04.591019Z","steps":["trace[1493274509] 'process raft request' (duration: 258.406812ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591501Z","caller":"traceutil/trace.go:171","msg":"trace[1603222368] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"243.771464ms","start":"2026-08-26T00:13:04.347718Z","end":"2026-08-26T00:13:04.591489Z","steps":["trace[1603222368] 'process raft request' (duration: 243.536597ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591456Z","caller":"traceutil/trace.go:171","msg":"trace[395942293] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"254.802643ms","start":"2026-08-26T00:13:04.336505Z","end":"2026-08-26T00:13:04.591308Z","steps":["trace[395942293] 'process raft request' (duration: 254.691715ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591084Z","caller":"traceutil/trace.go:171","msg":"trace[1666443467] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"257.150702ms","start":"2026-08-26T00:13:04.333922Z","end":"2026-08-26T00:13:04.591073Z","steps":["trace[1666443467] 'process raft request' (duration: 256.997275ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591736Z","caller":"traceutil/trace.go:171","msg":"trace[644924576] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"226.305306ms","start":"2026-08-26T00:13:04.365412Z","end":"2026-08-26T00:13:04.591718Z","steps":["trace[644924576] 'process raft request' (duration: 226.05908ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.591736Z","caller":"traceutil/trace.go:171","msg":"trace[350293816] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"241.025412ms","start":"2026-08-26T00:13:04.350599Z","end":"2026-08-26T00:13:04.591624Z","steps":["trace[350293816] 'process raft request' (duration: 240.718657ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.854689Z","caller":"traceutil/trace.go:171","msg":"trace[1510009176] transaction","detail":"{read_only:false; number_of_response:1; response_revision:494; }","duration":"323.922772ms","start":"2026-08-26T00:13:04.530745Z","end":"2026-08-26T00:13:04.854668Z","steps":["trace[1510009176] 'process raft request' (duration: 323.874402ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.855025Z","caller":"traceutil/trace.go:171","msg":"trace[1516257973] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"326.435749ms","start":"2026-08-26T00:13:04.52857Z","end":"2026-08-26T00:13:04.855005Z","steps":["trace[1516257973] 'process raft request' (duration: 325.808699ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.855157Z","caller":"traceutil/trace.go:171","msg":"trace[1659886323] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"324.572721ms","start":"2026-08-26T00:13:04.530559Z","end":"2026-08-26T00:13:04.855131Z","steps":["trace[1659886323] 'process raft request' (duration: 323.98624ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:04.855238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:04.530737Z","time spent":"324.01937ms","remote":"127.0.0.1:36944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":6938,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:13:04.855306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:04.528533Z","time spent":"326.544447ms","remote":"127.0.0.1:37412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-26T00:13:04.855331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:04.530548Z","time spent":"324.663909ms","remote":"127.0.0.1:36944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6888,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:04.86164Z","caller":"traceutil/trace.go:171","msg":"trace[1671280479] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"259.574322ms","start":"2026-08-26T00:13:04.602049Z","end":"2026-08-26T00:13:04.861623Z","steps":["trace[1671280479] 'process raft request' (duration: 258.821445ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.861729Z","caller":"traceutil/trace.go:171","msg":"trace[1514734464] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"247.212735ms","start":"2026-08-26T00:13:04.614495Z","end":"2026-08-26T00:13:04.861707Z","steps":["trace[1514734464] 'process raft request' (duration: 246.622445ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.861833Z","caller":"traceutil/trace.go:171","msg":"trace[377454671] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"243.842893ms","start":"2026-08-26T00:13:04.617982Z","end":"2026-08-26T00:13:04.861824Z","steps":["trace[377454671] 'process raft request' (duration: 243.193764ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.861954Z","caller":"traceutil/trace.go:171","msg":"trace[951984469] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"259.7254ms","start":"2026-08-26T00:13:04.602217Z","end":"2026-08-26T00:13:04.861942Z","steps":["trace[951984469] 'process raft request' (duration: 258.740137ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.862127Z","caller":"traceutil/trace.go:171","msg":"trace[1180828010] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"257.019427ms","start":"2026-08-26T00:13:04.605096Z","end":"2026-08-26T00:13:04.862116Z","steps":["trace[1180828010] 'process raft request' (duration: 255.893566ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.862192Z","caller":"traceutil/trace.go:171","msg":"trace[1444473137] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"193.767196ms","start":"2026-08-26T00:13:04.668414Z","end":"2026-08-26T00:13:04.862181Z","steps":["trace[1444473137] 'process raft request' (duration: 192.882312ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.862472Z","caller":"traceutil/trace.go:171","msg":"trace[1649267664] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"256.530893ms","start":"2026-08-26T00:13:04.605933Z","end":"2026-08-26T00:13:04.862464Z","steps":["trace[1649267664] 'process raft request' (duration: 255.091169ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.862131Z","caller":"traceutil/trace.go:171","msg":"trace[1842149077] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"238.474396ms","start":"2026-08-26T00:13:04.623629Z","end":"2026-08-26T00:13:04.862103Z","steps":["trace[1842149077] 'process raft request' (duration: 237.621361ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:04.862718Z","caller":"traceutil/trace.go:171","msg":"trace[404017577] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"253.652473ms","start":"2026-08-26T00:13:04.609057Z","end":"2026-08-26T00:13:04.86271Z","steps":["trace[404017577] 'process raft request' (duration: 252.013582ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:05.812948Z","caller":"traceutil/trace.go:171","msg":"trace[128919787] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"133.00189ms","start":"2026-08-26T00:13:05.679921Z","end":"2026-08-26T00:13:05.812922Z","steps":["trace[128919787] 'process raft request' (duration: 132.478208ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:05.865531Z","caller":"traceutil/trace.go:171","msg":"trace[1993200715] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"144.792257ms","start":"2026-08-26T00:13:05.720716Z","end":"2026-08-26T00:13:05.865508Z","steps":["trace[1993200715] 'process raft request' (duration: 144.661289ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:05.972346Z","caller":"traceutil/trace.go:171","msg":"trace[198918877] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"115.348289ms","start":"2026-08-26T00:13:05.856976Z","end":"2026-08-26T00:13:05.972324Z","steps":["trace[198918877] 'process raft request' (duration: 90.230949ms)","trace[198918877] 'compare' (duration: 24.902894ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:10.801968Z","caller":"traceutil/trace.go:171","msg":"trace[239081128] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"119.280846ms","start":"2026-08-26T00:13:10.682012Z","end":"2026-08-26T00:13:10.801293Z","steps":["trace[239081128] 'process raft request' (duration: 118.769805ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:10.967965Z","caller":"traceutil/trace.go:171","msg":"trace[2147200449] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"107.801424ms","start":"2026-08-26T00:13:10.860133Z","end":"2026-08-26T00:13:10.967934Z","steps":["trace[2147200449] 'process raft request' (duration: 77.551917ms)","trace[2147200449] 'compare' (duration: 30.05615ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:11.209798Z","caller":"traceutil/trace.go:171","msg":"trace[668379394] linearizableReadLoop","detail":"{readStateIndex:610; appliedIndex:609; }","duration":"128.80957ms","start":"2026-08-26T00:13:11.080969Z","end":"2026-08-26T00:13:11.209779Z","steps":["trace[668379394] 'read index received' (duration: 113.668525ms)","trace[668379394] 'applied index is now lower than readState.Index' (duration: 15.140265ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:13:11.209911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.928098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:13:11.20989Z","caller":"traceutil/trace.go:171","msg":"trace[574802445] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"160.557427ms","start":"2026-08-26T00:13:11.049306Z","end":"2026-08-26T00:13:11.209864Z","steps":["trace[574802445] 'process raft request' (duration: 145.372212ms)","trace[574802445] 'compare' (duration: 15.013268ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:11.20994Z","caller":"traceutil/trace.go:171","msg":"trace[912628425] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:588; }","duration":"128.969378ms","start":"2026-08-26T00:13:11.080961Z","end":"2026-08-26T00:13:11.20993Z","steps":["trace[912628425] 'agreement among raft nodes before linearized reading' (duration: 128.909269ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:11.210024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.660771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-26T00:13:11.210072Z","caller":"traceutil/trace.go:171","msg":"trace[1611770375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:588; }","duration":"125.72001ms","start":"2026-08-26T00:13:11.084338Z","end":"2026-08-26T00:13:11.210058Z","steps":["trace[1611770375] 'agreement among raft nodes before linearized reading' (duration: 125.566063ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:12.029359Z","caller":"traceutil/trace.go:171","msg":"trace[1465156462] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"644.96555ms","start":"2026-08-26T00:13:11.384365Z","end":"2026-08-26T00:13:12.029331Z","steps":["trace[1465156462] 'process raft request' (duration: 644.38646ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:12.029505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:11.384339Z","time spent":"645.085708ms","remote":"127.0.0.1:36978","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:13:12.030706Z","caller":"traceutil/trace.go:171","msg":"trace[519972714] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:613; }","duration":"367.318472ms","start":"2026-08-26T00:13:11.663369Z","end":"2026-08-26T00:13:12.030687Z","steps":["trace[519972714] 'read index received' (duration: 365.257975ms)","trace[519972714] 'applied index is now lower than readState.Index' (duration: 2.059727ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:12.030882Z","caller":"traceutil/trace.go:171","msg":"trace[1368505699] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"573.69211ms","start":"2026-08-26T00:13:11.457169Z","end":"2026-08-26T00:13:12.030862Z","steps":["trace[1368505699] 'process raft request' (duration: 573.392825ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:12.030947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.576968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:13:12.031203Z","caller":"traceutil/trace.go:171","msg":"trace[906167695] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:593; }","duration":"367.854913ms","start":"2026-08-26T00:13:11.663319Z","end":"2026-08-26T00:13:12.031174Z","steps":["trace[906167695] 'agreement among raft nodes before linearized reading' (duration: 367.459289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:12.031276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:11.6633Z","time spent":"367.957532ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:13:12.031137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:11.457124Z","time spent":"573.911516ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:13:14.0741Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.462672ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:13:14.074221Z","caller":"traceutil/trace.go:171","msg":"trace[1139901867] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"417.522771ms","start":"2026-08-26T00:13:13.656676Z","end":"2026-08-26T00:13:14.074198Z","steps":["trace[1139901867] 'process raft request' (duration: 38.714944ms)","trace[1139901867] 'compare' (duration: 378.269195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:13:14.075474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:13.656655Z","time spent":"417.61553ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":722,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:13:14.086026Z","caller":"traceutil/trace.go:171","msg":"trace[379715641] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"278.253873ms","start":"2026-08-26T00:13:13.807749Z","end":"2026-08-26T00:13:14.086003Z","steps":["trace[379715641] 'process raft request' (duration: 278.104435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:26.468032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.977207ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:13:26.468099Z","caller":"traceutil/trace.go:171","msg":"trace[1980436101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:755; }","duration":"386.103805ms","start":"2026-08-26T00:13:26.081981Z","end":"2026-08-26T00:13:26.468084Z","steps":["trace[1980436101] 'range keys from in-memory index tree' (duration: 385.884608ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:26.468127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:26.081959Z","time spent":"386.161204ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:13:26.468295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.971342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:13:26.468377Z","caller":"traceutil/trace.go:171","msg":"trace[1739950367] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:755; }","duration":"313.088471ms","start":"2026-08-26T00:13:26.15527Z","end":"2026-08-26T00:13:26.468358Z","steps":["trace[1739950367] 'range keys from in-memory index tree' (duration: 312.828195ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:26.468414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:13:26.155257Z","time spent":"313.14847ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:13:26.573631Z","caller":"traceutil/trace.go:171","msg":"trace[1941406078] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"101.366774ms","start":"2026-08-26T00:13:26.472241Z","end":"2026-08-26T00:13:26.573608Z","steps":["trace[1941406078] 'process raft request' (duration: 100.786493ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:26.576541Z","caller":"traceutil/trace.go:171","msg":"trace[25734447] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"102.250242ms","start":"2026-08-26T00:13:26.47427Z","end":"2026-08-26T00:13:26.57652Z","steps":["trace[25734447] 'process raft request' (duration: 102.098384ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:37.631669Z","caller":"traceutil/trace.go:171","msg":"trace[770169487] transaction","detail":"{read_only:false; response_revision:896; number_of_response:1; }","duration":"117.254289ms","start":"2026-08-26T00:13:37.514373Z","end":"2026-08-26T00:13:37.631627Z","steps":["trace[770169487] 'process raft request' (duration: 98.455656ms)","trace[770169487] 'compare' (duration: 18.636805ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:37.631727Z","caller":"traceutil/trace.go:171","msg":"trace[200886990] transaction","detail":"{read_only:false; response_revision:897; number_of_response:1; }","duration":"111.639566ms","start":"2026-08-26T00:13:37.520062Z","end":"2026-08-26T00:13:37.631701Z","steps":["trace[200886990] 'process raft request' (duration: 111.498868ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:13:56.248808Z","caller":"traceutil/trace.go:171","msg":"trace[1055068854] linearizableReadLoop","detail":"{readStateIndex:1197; appliedIndex:1196; }","duration":"166.894201ms","start":"2026-08-26T00:13:56.081893Z","end":"2026-08-26T00:13:56.248787Z","steps":["trace[1055068854] 'read index received' (duration: 166.605934ms)","trace[1055068854] 'applied index is now lower than readState.Index' (duration: 287.327µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:13:56.248871Z","caller":"traceutil/trace.go:171","msg":"trace[1420697145] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"227.712962ms","start":"2026-08-26T00:13:56.021124Z","end":"2026-08-26T00:13:56.248837Z","steps":["trace[1420697145] 'process raft request' (duration: 227.523637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:13:56.248924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.020644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:13:56.248957Z","caller":"traceutil/trace.go:171","msg":"trace[1148624221] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1161; }","duration":"167.101486ms","start":"2026-08-26T00:13:56.081846Z","end":"2026-08-26T00:13:56.248948Z","steps":["trace[1148624221] 'agreement among raft nodes before linearized reading' (duration: 167.034405ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:15.613475Z","caller":"traceutil/trace.go:171","msg":"trace[2002906982] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"103.808671ms","start":"2026-08-26T00:14:15.509643Z","end":"2026-08-26T00:14:15.613452Z","steps":["trace[2002906982] 'process raft request' (duration: 103.679529ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:17.844773Z","caller":"traceutil/trace.go:171","msg":"trace[316946893] transaction","detail":"{read_only:false; response_revision:1416; number_of_response:1; }","duration":"127.008392ms","start":"2026-08-26T00:14:17.717729Z","end":"2026-08-26T00:14:17.844738Z","steps":["trace[316946893] 'process raft request' (duration: 126.660237ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:17.84478Z","caller":"traceutil/trace.go:171","msg":"trace[424498015] transaction","detail":"{read_only:false; response_revision:1415; number_of_response:1; }","duration":"133.393117ms","start":"2026-08-26T00:14:17.711337Z","end":"2026-08-26T00:14:17.84473Z","steps":["trace[424498015] 'process raft request' (duration: 84.488519ms)","trace[424498015] 'compare' (duration: 48.435401ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:14:40.943214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.606868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:14:40.943467Z","caller":"traceutil/trace.go:171","msg":"trace[1098618758] transaction","detail":"{read_only:false; response_revision:1812; number_of_response:1; }","duration":"369.700666ms","start":"2026-08-26T00:14:40.573744Z","end":"2026-08-26T00:14:40.943445Z","steps":["trace[1098618758] 'process raft request' (duration: 135.713115ms)","trace[1098618758] 'compare' (duration: 233.158233ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:14:40.943378Z","caller":"traceutil/trace.go:171","msg":"trace[1172982977] linearizableReadLoop","detail":"{readStateIndex:1884; appliedIndex:1883; }","duration":"352.158343ms","start":"2026-08-26T00:14:40.591198Z","end":"2026-08-26T00:14:40.943356Z","steps":["trace[1172982977] 'read index received' (duration: 118.344113ms)","trace[1172982977] 'applied index is now lower than readState.Index' (duration: 233.81235ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:14:40.943638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:14:40.573715Z","time spent":"369.835417ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:14:40.943924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.725428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:14:40.943997Z","caller":"traceutil/trace.go:171","msg":"trace[1293431732] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1812; }","duration":"352.832229ms","start":"2026-08-26T00:14:40.591148Z","end":"2026-08-26T00:14:40.94398Z","steps":["trace[1293431732] 'agreement among raft nodes before linearized reading' (duration: 352.634117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:14:40.944213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:14:40.59113Z","time spent":"353.065641ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:14:40.945013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.416711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-acb16f9e-a057-44ee-994f-bbb62421012e\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-26T00:14:40.945119Z","caller":"traceutil/trace.go:171","msg":"trace[1200777435] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-acb16f9e-a057-44ee-994f-bbb62421012e; range_end:; response_count:1; response_revision:1812; }","duration":"180.579953ms","start":"2026-08-26T00:14:40.764515Z","end":"2026-08-26T00:14:40.945095Z","steps":["trace[1200777435] 'agreement among raft nodes before linearized reading' (duration: 180.26039ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:41.148326Z","caller":"traceutil/trace.go:171","msg":"trace[577475084] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"198.570785ms","start":"2026-08-26T00:14:40.949733Z","end":"2026-08-26T00:14:41.148304Z","steps":["trace[577475084] 'process raft request' (duration: 192.079285ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:14:41.148534Z","caller":"traceutil/trace.go:171","msg":"trace[731696393] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"195.85539ms","start":"2026-08-26T00:14:40.952647Z","end":"2026-08-26T00:14:41.148503Z","steps":["trace[731696393] 'process raft request' (duration: 195.546267ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:15.178748Z","caller":"traceutil/trace.go:171","msg":"trace[1039251898] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"111.644263ms","start":"2026-08-26T00:15:15.067064Z","end":"2026-08-26T00:15:15.178708Z","steps":["trace[1039251898] 'process raft request' (duration: 34.964428ms)","trace[1039251898] 'compare' (duration: 76.449414ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:15:15.179608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.106503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:15:15.179926Z","caller":"traceutil/trace.go:171","msg":"trace[861039082] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2056; }","duration":"100.266054ms","start":"2026-08-26T00:15:15.079437Z","end":"2026-08-26T00:15:15.179703Z","steps":["trace[861039082] 'agreement among raft nodes before linearized reading' (duration: 99.170311ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:15.51221Z","caller":"traceutil/trace.go:171","msg":"trace[572585077] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2135; }","duration":"209.76447ms","start":"2026-08-26T00:15:15.302423Z","end":"2026-08-26T00:15:15.512188Z","steps":["trace[572585077] 'read index received' (duration: 158.40553ms)","trace[572585077] 'applied index is now lower than readState.Index' (duration: 51.35806ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:15.51228Z","caller":"traceutil/trace.go:171","msg":"trace[138631283] transaction","detail":"{read_only:false; response_revision:2057; number_of_response:1; }","duration":"248.096667ms","start":"2026-08-26T00:15:15.26414Z","end":"2026-08-26T00:15:15.512237Z","steps":["trace[138631283] 'process raft request' (duration: 196.708267ms)","trace[138631283] 'compare' (duration: 51.02874ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:15:15.512407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.957781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:15:15.51245Z","caller":"traceutil/trace.go:171","msg":"trace[588697385] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2058; }","duration":"210.045771ms","start":"2026-08-26T00:15:15.302392Z","end":"2026-08-26T00:15:15.512438Z","steps":["trace[588697385] 'agreement among raft nodes before linearized reading' (duration: 209.89857ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:15.512557Z","caller":"traceutil/trace.go:171","msg":"trace[136635366] transaction","detail":"{read_only:false; response_revision:2058; number_of_response:1; }","duration":"247.172095ms","start":"2026-08-26T00:15:15.265375Z","end":"2026-08-26T00:15:15.512547Z","steps":["trace[136635366] 'process raft request' (duration: 246.765914ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:15.644401Z","caller":"traceutil/trace.go:171","msg":"trace[1133994017] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"112.286454ms","start":"2026-08-26T00:15:15.532091Z","end":"2026-08-26T00:15:15.644378Z","steps":["trace[1133994017] 'read index received' (duration: 84.589484ms)","trace[1133994017] 'applied index is now lower than readState.Index' (duration: 27.69608ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:15.6445Z","caller":"traceutil/trace.go:171","msg":"trace[469713988] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"127.166431ms","start":"2026-08-26T00:15:15.517277Z","end":"2026-08-26T00:15:15.644443Z","steps":["trace[469713988] 'process raft request' (duration: 99.513232ms)","trace[469713988] 'compare' (duration: 27.469129ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:15:15.644679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.533665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:15:15.644774Z","caller":"traceutil/trace.go:171","msg":"trace[232219972] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2060; }","duration":"112.651265ms","start":"2026-08-26T00:15:15.532078Z","end":"2026-08-26T00:15:15.64473Z","steps":["trace[232219972] 'agreement among raft nodes before linearized reading' (duration: 112.419034ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:16.206648Z","caller":"traceutil/trace.go:171","msg":"trace[2040111786] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"270.786705ms","start":"2026-08-26T00:15:15.935834Z","end":"2026-08-26T00:15:16.20662Z","steps":["trace[2040111786] 'process raft request' (duration: 270.632425ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:16.214021Z","caller":"traceutil/trace.go:171","msg":"trace[467552610] linearizableReadLoop","detail":"{readStateIndex:2141; appliedIndex:2139; }","duration":"132.564486ms","start":"2026-08-26T00:15:16.081432Z","end":"2026-08-26T00:15:16.213996Z","steps":["trace[467552610] 'read index received' (duration: 125.007859ms)","trace[467552610] 'applied index is now lower than readState.Index' (duration: 7.555557ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:16.214142Z","caller":"traceutil/trace.go:171","msg":"trace[1516912790] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"160.269491ms","start":"2026-08-26T00:15:16.05386Z","end":"2026-08-26T00:15:16.214129Z","steps":["trace[1516912790] 'process raft request' (duration: 159.969711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:15:16.214338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.891817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:15:16.214375Z","caller":"traceutil/trace.go:171","msg":"trace[339860595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2062; }","duration":"132.947667ms","start":"2026-08-26T00:15:16.081418Z","end":"2026-08-26T00:15:16.214366Z","steps":["trace[339860595] 'agreement among raft nodes before linearized reading' (duration: 132.871677ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:16.356811Z","caller":"traceutil/trace.go:171","msg":"trace[1435239829] linearizableReadLoop","detail":"{readStateIndex:2143; appliedIndex:2141; }","duration":"122.28875ms","start":"2026-08-26T00:15:16.234494Z","end":"2026-08-26T00:15:16.356783Z","steps":["trace[1435239829] 'read index received' (duration: 59.386751ms)","trace[1435239829] 'applied index is now lower than readState.Index' (duration: 62.901189ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:15:16.356849Z","caller":"traceutil/trace.go:171","msg":"trace[1617374583] transaction","detail":"{read_only:false; response_revision:2063; number_of_response:1; }","duration":"138.119019ms","start":"2026-08-26T00:15:16.218692Z","end":"2026-08-26T00:15:16.356811Z","steps":["trace[1617374583] 'process raft request' (duration: 75.104299ms)","trace[1617374583] 'compare' (duration: 62.772349ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:15:16.357057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.540201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-26T00:15:16.357107Z","caller":"traceutil/trace.go:171","msg":"trace[1097060308] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2064; }","duration":"122.632631ms","start":"2026-08-26T00:15:16.234463Z","end":"2026-08-26T00:15:16.357096Z","steps":["trace[1097060308] 'agreement among raft nodes before linearized reading' (duration: 122.428361ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:16.357166Z","caller":"traceutil/trace.go:171","msg":"trace[1076967985] transaction","detail":"{read_only:false; response_revision:2064; number_of_response:1; }","duration":"138.392839ms","start":"2026-08-26T00:15:16.21874Z","end":"2026-08-26T00:15:16.357133Z","steps":["trace[1076967985] 'process raft request' (duration: 137.995438ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:15:16.707929Z","caller":"traceutil/trace.go:171","msg":"trace[156872046] transaction","detail":"{read_only:false; response_revision:2065; number_of_response:1; }","duration":"346.470306ms","start":"2026-08-26T00:15:16.361426Z","end":"2026-08-26T00:15:16.707896Z","steps":["trace[156872046] 'process raft request' (duration: 253.584165ms)","trace[156872046] 'compare' (duration: 92.681331ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:15:16.708145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:15:16.361401Z","time spent":"346.667306ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:20.372257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.153802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:20.373001Z","caller":"traceutil/trace.go:171","msg":"trace[1967028539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2504; }","duration":"291.801651ms","start":"2026-08-26T00:16:20.081044Z","end":"2026-08-26T00:16:20.372846Z","steps":["trace[1967028539] 'range keys from in-memory index tree' (duration: 291.085733ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:47.16074Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:47.661538Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:48.162347Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:48.663292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:49.164407Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:49.613055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.58584426s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-08-26T00:16:49.613141Z","caller":"traceutil/trace.go:171","msg":"trace[1614352475] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2647; }","duration":"3.585980628s","start":"2026-08-26T00:16:46.027141Z","end":"2026-08-26T00:16:49.613122Z","steps":["trace[1614352475] 'range keys from in-memory index tree' (duration: 3.58567483s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.613182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.027122Z","time spent":"3.586050158s","remote":"127.0.0.1:37078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1343,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-08-26T00:16:49.613461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.535218308s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-26T00:16:49.613506Z","caller":"traceutil/trace.go:171","msg":"trace[640216744] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2647; }","duration":"3.535285877s","start":"2026-08-26T00:16:46.078208Z","end":"2026-08-26T00:16:49.613494Z","steps":["trace[640216744] 'range keys from in-memory index tree' (duration: 3.535135368s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.613537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.078197Z","time spent":"3.535330467s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:16:49.613697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.53238081s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:49.613724Z","caller":"traceutil/trace.go:171","msg":"trace[91142737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2647; }","duration":"3.53241878s","start":"2026-08-26T00:16:46.081297Z","end":"2026-08-26T00:16:49.613716Z","steps":["trace[91142737] 'range keys from in-memory index tree' (duration: 3.53234993s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:49.61375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.081292Z","time spent":"3.53245015s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:49.665403Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:50.082828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000376247s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:50.082911Z","caller":"traceutil/trace.go:171","msg":"trace[373082990] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000505616s","start":"2026-08-26T00:16:48.082388Z","end":"2026-08-26T00:16:50.082893Z","steps":["trace[373082990] 'agreement among raft nodes before linearized reading' (duration: 2.000396287s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:50.082959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.082374Z","time spent":"2.000574626s","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:50 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:50.166336Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:50.666811Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:51.167834Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:51.578744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.581657Z","time spent":"4.997080826s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:51.659763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999036724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:51.659934Z","caller":"traceutil/trace.go:171","msg":"trace[1042924719] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999249373s","start":"2026-08-26T00:16:46.660666Z","end":"2026-08-26T00:16:51.659916Z","steps":["trace[1042924719] 'agreement among raft nodes before linearized reading' (duration: 4.999061344s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:51.659965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.660654Z","time spent":"4.999303903s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/26 00:16:51 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:51.66904Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:52.090528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000319395s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:52.090743Z","caller":"traceutil/trace.go:171","msg":"trace[938621320] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000596554s","start":"2026-08-26T00:16:50.090126Z","end":"2026-08-26T00:16:52.090723Z","steps":["trace[938621320] 'agreement among raft nodes before linearized reading' (duration: 2.000361545s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:52.09085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.090111Z","time spent":"2.000725213s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:52.136743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999021826s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:52.136824Z","caller":"traceutil/trace.go:171","msg":"trace[578578505] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999151625s","start":"2026-08-26T00:16:47.137654Z","end":"2026-08-26T00:16:52.136806Z","steps":["trace[578578505] 'agreement among raft nodes before linearized reading' (duration: 4.999047506s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:52.136903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.137638Z","time spent":"4.999250825s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:16:52 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:52.170142Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:52.521802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.522766Z","time spent":"4.999012043s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:52 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:52.670987Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:52.998385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998919894s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:52.998515Z","caller":"traceutil/trace.go:171","msg":"trace[596516315] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999089353s","start":"2026-08-26T00:16:47.999391Z","end":"2026-08-26T00:16:52.998481Z","steps":["trace[596516315] 'agreement among raft nodes before linearized reading' (duration: 4.998953243s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:52.998593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.999364Z","time spent":"4.999211582s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:16:52 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:53.17116Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553751,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:53.661671Z","caller":"etcdserver/v3_server.go:909","msg":"timed out waiting for read index response (local node might have slow network)","timeout":"7s"} {"level":"warn","ts":"2026-08-26T00:16:53.950059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.949621Z","time spent":"7.000429128s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:54.091242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999935629s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:54.091348Z","caller":"traceutil/trace.go:171","msg":"trace[2078165969] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000064309s","start":"2026-08-26T00:16:52.091263Z","end":"2026-08-26T00:16:54.091327Z","steps":["trace[2078165969] 'agreement among raft nodes before linearized reading' (duration: 1.999931339s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:54.091398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.091208Z","time spent":"2.000179468s","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:54.162223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:54.26163Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"23eba03b693faebb","error":"etcdserver: request timed out"} {"level":"warn","ts":"2026-08-26T00:16:54.662623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:55.163193Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:55.663816Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:56.084436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.61962Z","time spent":"6.464809408s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.099428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000185055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-26T00:16:56.099491Z","caller":"traceutil/trace.go:171","msg":"trace[1671468494] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000309635s","start":"2026-08-26T00:16:54.099165Z","end":"2026-08-26T00:16:56.099475Z","steps":["trace[1671468494] 'agreement among raft nodes before linearized reading' (duration: 2.000218015s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.099522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.099142Z","time spent":"2.000374473s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.164246Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:56.578598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998828447s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.578682Z","caller":"traceutil/trace.go:171","msg":"trace[895512544] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"4.998958086s","start":"2026-08-26T00:16:51.579703Z","end":"2026-08-26T00:16:56.578661Z","steps":["trace[895512544] 'agreement among raft nodes before linearized reading' (duration: 4.998850377s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.578748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:51.579684Z","time spent":"4.999052806s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":0,"request content":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.660774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.998278131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.66092Z","caller":"traceutil/trace.go:171","msg":"trace[1635166926] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"2.998470071s","start":"2026-08-26T00:16:53.662431Z","end":"2026-08-26T00:16:56.660901Z","steps":["trace[1635166926] 'agreement among raft nodes before linearized reading' (duration: 2.998302021s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.660971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.662417Z","time spent":"2.9985431s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.664522Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:56.765972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.76508Z","time spent":"7.000886947s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:56.836626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999300146s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.836749Z","caller":"traceutil/trace.go:171","msg":"trace[1633928362] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"9.999462675s","start":"2026-08-26T00:16:46.837269Z","end":"2026-08-26T00:16:56.836732Z","steps":["trace[1633928362] 'agreement among raft nodes before linearized reading' (duration: 9.999327456s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.836825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.837254Z","time spent":"9.999559885s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:56.849856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.002244059s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:56.849955Z","caller":"traceutil/trace.go:171","msg":"trace[458853052] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; }","duration":"10.002391108s","start":"2026-08-26T00:16:46.847542Z","end":"2026-08-26T00:16:56.849933Z","steps":["trace[458853052] 'agreement among raft nodes before linearized reading' (duration: 10.002277669s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:56.850111Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:46.84752Z","time spent":"10.002577108s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} 2026/08/26 00:16:56 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.136938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.997556642s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.137016Z","caller":"traceutil/trace.go:171","msg":"trace[947769940] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"2.997695902s","start":"2026-08-26T00:16:54.139303Z","end":"2026-08-26T00:16:57.136999Z","steps":["trace[947769940] 'agreement among raft nodes before linearized reading' (duration: 2.997597751s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.137061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:54.139286Z","time spent":"2.997766222s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.164902Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:57.521757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.998282396s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.521837Z","caller":"traceutil/trace.go:171","msg":"trace[2072438705] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; }","duration":"4.998411246s","start":"2026-08-26T00:16:52.523407Z","end":"2026-08-26T00:16:57.521818Z","steps":["trace[2072438705] 'agreement among raft nodes before linearized reading' (duration: 4.998318026s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.521879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.523385Z","time spent":"4.998484705s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/08db1feb.percona.com\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.546463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.150223526s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:57.546606Z","caller":"traceutil/trace.go:171","msg":"trace[876230580] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; }","duration":"10.150417655s","start":"2026-08-26T00:16:47.396171Z","end":"2026-08-26T00:16:57.546588Z","steps":["trace[876230580] 'agreement among raft nodes before linearized reading' (duration: 10.150263476s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:57.546649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.396136Z","time spent":"10.150501795s","remote":"127.0.0.1:37064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":0,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} 2026/08/26 00:16:57 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:57.665857Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:57.920379Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.919277Z","time spent":"7.001095945s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-26T00:16:58.000361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.000076983s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:58.000435Z","caller":"traceutil/trace.go:171","msg":"trace[1715891135] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"3.000161132s","start":"2026-08-26T00:16:55.000258Z","end":"2026-08-26T00:16:58.000419Z","steps":["trace[1715891135] 'agreement among raft nodes before linearized reading' (duration: 3.000075053s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:58.000499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:55.000208Z","time spent":"3.000282662s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} 2026/08/26 00:16:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" 2026/08/26 00:16:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:58.024141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.999550128s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:58.024201Z","caller":"traceutil/trace.go:171","msg":"trace[1153340254] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; }","duration":"9.999647288s","start":"2026-08-26T00:16:48.024539Z","end":"2026-08-26T00:16:58.024187Z","steps":["trace[1153340254] 'agreement among raft nodes before linearized reading' (duration: 9.999577958s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:58.02424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.024527Z","time spent":"9.999702607s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":0,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} 2026/08/26 00:16:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:58.099597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999916756s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:16:58.099687Z","caller":"traceutil/trace.go:171","msg":"trace[2119532232] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000061396s","start":"2026-08-26T00:16:56.099604Z","end":"2026-08-26T00:16:58.099666Z","steps":["trace[2119532232] 'agreement among raft nodes before linearized reading' (duration: 1.999950006s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:58.099732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.099588Z","time spent":"2.000135315s","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:16:58 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:16:58.166177Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:58.666344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:59.16732Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553759,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:16:59.244546Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"12.662596517s","expected-duration":"1s"} {"level":"warn","ts":"2026-08-26T00:16:59.378736Z","caller":"etcdserver/util.go:123","msg":"failed to apply request","took":"90.66µs","request":"header: lease_revoke:","response":"size:29","error":"lease not found"} {"level":"info","ts":"2026-08-26T00:16:59.378827Z","caller":"traceutil/trace.go:171","msg":"trace[306560435] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2653; }","duration":"5.424717656s","start":"2026-08-26T00:16:53.954086Z","end":"2026-08-26T00:16:59.378804Z","steps":["trace[306560435] 'process raft request' (duration: 5.424516837s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.378947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.954066Z","time spent":"5.424816226s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":69,"response count":0,"response size":722,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.37895Z","caller":"etcdserver/server.go:1165","msg":"failed to revoke lease","lease-id":"23eba03b693faebb","error":"lease not found"} {"level":"info","ts":"2026-08-26T00:16:59.378993Z","caller":"traceutil/trace.go:171","msg":"trace[2022602302] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"2.796749283s","start":"2026-08-26T00:16:56.582191Z","end":"2026-08-26T00:16:59.37894Z","steps":["trace[2022602302] 'process raft request' (duration: 2.796612864s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.379166Z","caller":"traceutil/trace.go:171","msg":"trace[1911327492] transaction","detail":"{read_only:false; response_revision:2655; number_of_response:1; }","duration":"2.71651991s","start":"2026-08-26T00:16:56.662608Z","end":"2026-08-26T00:16:59.379128Z","steps":["trace[1911327492] 'process raft request' (duration: 2.716247982s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.379215Z","caller":"traceutil/trace.go:171","msg":"trace[1079156559] transaction","detail":"{read_only:false; response_revision:2656; number_of_response:1; }","duration":"2.540259572s","start":"2026-08-26T00:16:56.838946Z","end":"2026-08-26T00:16:59.379205Z","steps":["trace[1079156559] 'process raft request' (duration: 2.539978933s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.379285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.838927Z","time spent":"2.540310491s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.37933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.662588Z","time spent":"2.71663981s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.379487Z","caller":"traceutil/trace.go:171","msg":"trace[348959039] transaction","detail":"{read_only:false; response_revision:2657; number_of_response:1; }","duration":"2.528454888s","start":"2026-08-26T00:16:56.851022Z","end":"2026-08-26T00:16:59.379477Z","steps":["trace[348959039] 'process raft request' (duration: 2.527942431s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.379546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.850996Z","time spent":"2.528521948s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.379606Z","caller":"traceutil/trace.go:171","msg":"trace[702264018] transaction","detail":"{read_only:false; response_revision:2658; number_of_response:1; }","duration":"2.239596197s","start":"2026-08-26T00:16:57.14Z","end":"2026-08-26T00:16:59.379597Z","steps":["trace[702264018] 'process raft request' (duration: 2.23908487s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.379665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.139981Z","time spent":"2.239650677s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":456,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:16:59.37909Z","caller":"traceutil/trace.go:171","msg":"trace[368617570] transaction","detail":"{read_only:false; number_of_response:1; response_revision:2655; }","duration":"2.609225179s","start":"2026-08-26T00:16:56.769852Z","end":"2026-08-26T00:16:59.379078Z","steps":["trace[368617570] 'process raft request' (duration: 2.60904301s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.379849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.769827Z","time spent":"2.609998925s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":45,"response count":0,"response size":571,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.380061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.582169Z","time spent":"2.796995542s","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:16:59.428874Z","caller":"traceutil/trace.go:171","msg":"trace[1652872016] linearizableReadLoop","detail":"{readStateIndex:2764; appliedIndex:2748; }","duration":"5.767063935s","start":"2026-08-26T00:16:53.661778Z","end":"2026-08-26T00:16:59.428842Z","steps":["trace[1652872016] 'read index received' (duration: 5.584105704s)","trace[1652872016] 'applied index is now lower than readState.Index' (duration: 182.957041ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:59.42908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.493256997s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"warn","ts":"2026-08-26T00:16:59.429294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.688714366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.429352Z","caller":"traceutil/trace.go:171","msg":"trace[798600781] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2660; }","duration":"6.688803686s","start":"2026-08-26T00:16:52.740532Z","end":"2026-08-26T00:16:59.429336Z","steps":["trace[798600781] 'agreement among raft nodes before linearized reading' (duration: 6.688693986s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.429448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.740509Z","time spent":"6.688924175s","remote":"127.0.0.1:37388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":3,"response size":31,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.429466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.074914696s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.429546Z","caller":"traceutil/trace.go:171","msg":"trace[9892456] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2660; }","duration":"6.075052296s","start":"2026-08-26T00:16:53.354474Z","end":"2026-08-26T00:16:59.429526Z","steps":["trace[9892456] 'agreement among raft nodes before linearized reading' (duration: 6.074795487s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.429597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.35446Z","time spent":"6.075126915s","remote":"127.0.0.1:37144","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.429589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"7.255684307s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.144\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.429656Z","caller":"traceutil/trace.go:171","msg":"trace[2110155410] range","detail":"{range_begin:/registry/masterleases/162.253.55.144; range_end:; response_count:0; response_revision:2660; }","duration":"7.255809186s","start":"2026-08-26T00:16:52.173834Z","end":"2026-08-26T00:16:59.429643Z","steps":["trace[2110155410] 'agreement among raft nodes before linearized reading' (duration: 7.255665747s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.429691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:52.173815Z","time spent":"7.255870726s","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":29,"request content":"key:\"/registry/masterleases/162.253.55.144\" "} {"level":"warn","ts":"2026-08-26T00:16:59.429724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"8.785821699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.429785Z","caller":"traceutil/trace.go:171","msg":"trace[1408937773] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:2660; }","duration":"8.785981068s","start":"2026-08-26T00:16:50.643788Z","end":"2026-08-26T00:16:59.429769Z","steps":["trace[1408937773] 'agreement among raft nodes before linearized reading' (duration: 8.785843579s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.429834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:50.643771Z","time spent":"8.786049988s","remote":"127.0.0.1:37022","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":31,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.42991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.123132098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.429993Z","caller":"traceutil/trace.go:171","msg":"trace[653964204] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:2660; }","duration":"6.123243007s","start":"2026-08-26T00:16:53.30672Z","end":"2026-08-26T00:16:59.429964Z","steps":["trace[653964204] 'agreement among raft nodes before linearized reading' (duration: 6.123094858s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.729275873s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:59.430047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:53.306704Z","time spent":"6.123333967s","remote":"127.0.0.1:56824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.010386023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:16:59.430071Z","caller":"traceutil/trace.go:171","msg":"trace[1395071609] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:2660; }","duration":"11.729332223s","start":"2026-08-26T00:16:47.700715Z","end":"2026-08-26T00:16:59.430047Z","steps":["trace[1395071609] 'agreement among raft nodes before linearized reading' (duration: 11.729275653s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.43009Z","caller":"traceutil/trace.go:171","msg":"trace[214043276] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:2660; }","duration":"11.010483942s","start":"2026-08-26T00:16:48.419598Z","end":"2026-08-26T00:16:59.430082Z","steps":["trace[214043276] 'agreement among raft nodes before linearized reading' (duration: 11.010394202s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.700704Z","time spent":"11.729393083s","remote":"127.0.0.1:37114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.419578Z","time spent":"11.010527832s","remote":"127.0.0.1:37086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":59,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.122150331s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18cf32c3b9c74618\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-08-26T00:16:59.430258Z","caller":"traceutil/trace.go:171","msg":"trace[990598930] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18cf32c3b9c74618; range_end:; response_count:1; response_revision:2660; }","duration":"11.1222663s","start":"2026-08-26T00:16:48.307974Z","end":"2026-08-26T00:16:59.43024Z","steps":["trace[990598930] 'agreement among raft nodes before linearized reading' (duration: 11.122024951s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.43028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"10.784468637s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.4303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.307959Z","time spent":"11.12232917s","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":805,"request content":"key:\"/registry/events/auth-system/keycloak-0.18cf32c3b9c74618\" "} {"level":"info","ts":"2026-08-26T00:16:59.43031Z","caller":"traceutil/trace.go:171","msg":"trace[1707685873] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:2660; }","duration":"10.784550237s","start":"2026-08-26T00:16:48.645753Z","end":"2026-08-26T00:16:59.430303Z","steps":["trace[1707685873] 'agreement among raft nodes before linearized reading' (duration: 10.784494277s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:48.64573Z","time spent":"10.784607746s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":14,"response size":31,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.186897743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.430399Z","caller":"traceutil/trace.go:171","msg":"trace[1791906973] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2660; }","duration":"3.186988183s","start":"2026-08-26T00:16:56.243403Z","end":"2026-08-26T00:16:59.430391Z","steps":["trace[1791906973] 'agreement among raft nodes before linearized reading' (duration: 3.186924213s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.243385Z","time spent":"3.187033493s","remote":"127.0.0.1:56806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.729158684s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:16:59.430573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.323895136s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:59.430602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.409751117s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.430635Z","caller":"traceutil/trace.go:171","msg":"trace[1425811595] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2660; }","duration":"1.323981806s","start":"2026-08-26T00:16:58.10664Z","end":"2026-08-26T00:16:59.430622Z","steps":["trace[1425811595] 'agreement among raft nodes before linearized reading' (duration: 1.323896796s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.833200712s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:59.430663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.016261469s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-26T00:16:59.430683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:58.10663Z","time spent":"1.324041756s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:16:59.430725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.328929261s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7111"} {"level":"info","ts":"2026-08-26T00:16:59.430647Z","caller":"traceutil/trace.go:171","msg":"trace[1324042934] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2660; }","duration":"2.409843877s","start":"2026-08-26T00:16:57.02079Z","end":"2026-08-26T00:16:59.430634Z","steps":["trace[1324042934] 'agreement among raft nodes before linearized reading' (duration: 2.409778607s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.430735Z","caller":"traceutil/trace.go:171","msg":"trace[616635192] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2660; }","duration":"2.016355358s","start":"2026-08-26T00:16:57.41435Z","end":"2026-08-26T00:16:59.430705Z","steps":["trace[616635192] 'agreement among raft nodes before linearized reading' (duration: 2.016264249s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.430756Z","caller":"traceutil/trace.go:171","msg":"trace[592370001] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2660; }","duration":"1.328991081s","start":"2026-08-26T00:16:58.101757Z","end":"2026-08-26T00:16:59.430748Z","steps":["trace[592370001] 'agreement among raft nodes before linearized reading' (duration: 1.328782892s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.020771Z","time spent":"2.409994846s","remote":"127.0.0.1:37148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:58.101743Z","time spent":"1.329029451s","remote":"127.0.0.1:37064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7135,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-26T00:16:59.430795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.507180183s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-08-26T00:16:59.43068Z","caller":"traceutil/trace.go:171","msg":"trace[37741162] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2660; }","duration":"2.833230092s","start":"2026-08-26T00:16:56.597443Z","end":"2026-08-26T00:16:59.430673Z","steps":["trace[37741162] 'agreement among raft nodes before linearized reading' (duration: 2.833185782s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.43082Z","caller":"traceutil/trace.go:171","msg":"trace[1576234899] range","detail":"{range_begin:/registry/leases/ingress-nginx/ingress-nginx-leader; range_end:; response_count:1; response_revision:2660; }","duration":"1.507251163s","start":"2026-08-26T00:16:57.923562Z","end":"2026-08-26T00:16:59.430813Z","steps":["trace[1576234899] 'agreement among raft nodes before linearized reading' (duration: 1.506947054s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.923537Z","time spent":"1.507303483s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":514,"request content":"key:\"/registry/leases/ingress-nginx/ingress-nginx-leader\" "} {"level":"warn","ts":"2026-08-26T00:16:59.430843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:56.597396Z","time spent":"2.833426331s","remote":"127.0.0.1:37150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:16:59.43061Z","caller":"traceutil/trace.go:171","msg":"trace[271867408] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:2660; }","duration":"1.729235144s","start":"2026-08-26T00:16:57.701358Z","end":"2026-08-26T00:16:59.430593Z","steps":["trace[271867408] 'agreement among raft nodes before linearized reading' (duration: 1.729176234s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.430988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.701338Z","time spent":"1.729641092s","remote":"127.0.0.1:37110","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:16:59.430952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"11.759031127s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-08-26T00:16:59.430789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:57.41433Z","time spent":"2.016444888s","remote":"127.0.0.1:56822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:16:59.431073Z","caller":"traceutil/trace.go:171","msg":"trace[1063508708] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2660; }","duration":"11.759166577s","start":"2026-08-26T00:16:47.671887Z","end":"2026-08-26T00:16:59.431054Z","steps":["trace[1063508708] 'agreement among raft nodes before linearized reading' (duration: 11.758792039s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.431141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:47.671869Z","time spent":"11.759256166s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-26T00:16:59.429505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"9.811152173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/auth-system/keycloak\" ","response":"range_response_count:1 size:7375"} {"level":"info","ts":"2026-08-26T00:16:59.429183Z","caller":"traceutil/trace.go:171","msg":"trace[647740578] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2660; }","duration":"9.493401067s","start":"2026-08-26T00:16:49.935749Z","end":"2026-08-26T00:16:59.42915Z","steps":["trace[647740578] 'agreement among raft nodes before linearized reading' (duration: 9.493098338s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:16:59.431403Z","caller":"traceutil/trace.go:171","msg":"trace[649526451] range","detail":"{range_begin:/registry/statefulsets/auth-system/keycloak; range_end:; response_count:1; response_revision:2660; }","duration":"9.813131083s","start":"2026-08-26T00:16:49.61826Z","end":"2026-08-26T00:16:59.431391Z","steps":["trace[649526451] 'agreement among raft nodes before linearized reading' (duration: 9.810768244s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.431421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.935727Z","time spent":"9.495681206s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":583,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:16:59.431435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:49.618248Z","time spent":"9.813178633s","remote":"127.0.0.1:37338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":7399,"request content":"key:\"/registry/statefulsets/auth-system/keycloak\" "} {"level":"warn","ts":"2026-08-26T00:16:59.985121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.933591ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:16:59.985315Z","caller":"traceutil/trace.go:171","msg":"trace[462844428] linearizableReadLoop","detail":"{readStateIndex:2773; appliedIndex:2771; }","duration":"457.763319ms","start":"2026-08-26T00:16:59.527535Z","end":"2026-08-26T00:16:59.985298Z","steps":["trace[462844428] 'read index received' (duration: 24.796848ms)","trace[462844428] 'applied index is now lower than readState.Index' (duration: 432.965481ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:16:59.985422Z","caller":"traceutil/trace.go:171","msg":"trace[1380042339] transaction","detail":"{read_only:false; response_revision:2667; number_of_response:1; }","duration":"531.067418ms","start":"2026-08-26T00:16:59.454339Z","end":"2026-08-26T00:16:59.985406Z","steps":["trace[1380042339] 'process raft request' (duration: 97.779699ms)","trace[1380042339] 'compare' (duration: 432.331164ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:16:59.985473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:59.454325Z","time spent":"531.121858ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:16:59.985671Z","caller":"traceutil/trace.go:171","msg":"trace[63909510] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"459.60859ms","start":"2026-08-26T00:16:59.526052Z","end":"2026-08-26T00:16:59.985661Z","steps":["trace[63909510] 'process raft request' (duration: 459.192612ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.985732Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:59.526016Z","time spent":"459.6785ms","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:16:59.985994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.180178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7303"} {"level":"info","ts":"2026-08-26T00:16:59.986057Z","caller":"traceutil/trace.go:171","msg":"trace[1036747066] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:2668; }","duration":"454.287018ms","start":"2026-08-26T00:16:59.531755Z","end":"2026-08-26T00:16:59.986042Z","steps":["trace[1036747066] 'agreement among raft nodes before linearized reading' (duration: 454.024438ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.986095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:59.531734Z","time spent":"454.353097ms","remote":"127.0.0.1:37064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":7327,"request content":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" "} {"level":"warn","ts":"2026-08-26T00:16:59.986262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.375953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:16:59.986353Z","caller":"traceutil/trace.go:171","msg":"trace[1697863284] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2668; }","duration":"534.521953ms","start":"2026-08-26T00:16:59.451814Z","end":"2026-08-26T00:16:59.986336Z","steps":["trace[1697863284] 'agreement among raft nodes before linearized reading' (duration: 533.969014ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:16:59.986416Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:16:59.451806Z","time spent":"534.601182ms","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-26T00:17:00.209059Z","caller":"traceutil/trace.go:171","msg":"trace[2049203417] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"214.3673ms","start":"2026-08-26T00:16:59.994659Z","end":"2026-08-26T00:17:00.209026Z","steps":["trace[2049203417] 'process raft request' (duration: 203.300595ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:00.218206Z","caller":"traceutil/trace.go:171","msg":"trace[138354911] transaction","detail":"{read_only:false; response_revision:2670; number_of_response:1; }","duration":"223.092137ms","start":"2026-08-26T00:16:59.995101Z","end":"2026-08-26T00:17:00.218193Z","steps":["trace[138354911] 'process raft request' (duration: 222.893898ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:00.218151Z","caller":"traceutil/trace.go:171","msg":"trace[128772919] linearizableReadLoop","detail":"{readStateIndex:2775; appliedIndex:2773; }","duration":"202.358329ms","start":"2026-08-26T00:17:00.015771Z","end":"2026-08-26T00:17:00.21813Z","steps":["trace[128772919] 'read index received' (duration: 182.204599ms)","trace[128772919] 'applied index is now lower than readState.Index' (duration: 20.15285ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:00.218753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.907067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-26T00:17:00.218886Z","caller":"traceutil/trace.go:171","msg":"trace[625855256] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2670; }","duration":"203.124516ms","start":"2026-08-26T00:17:00.015736Z","end":"2026-08-26T00:17:00.21886Z","steps":["trace[625855256] 'agreement among raft nodes before linearized reading' (duration: 202.607728ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:00.63036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.388107ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-26T00:17:00.630459Z","caller":"traceutil/trace.go:171","msg":"trace[2107429357] transaction","detail":"{read_only:false; response_revision:2672; number_of_response:1; }","duration":"396.310921ms","start":"2026-08-26T00:17:00.234128Z","end":"2026-08-26T00:17:00.630438Z","steps":["trace[2107429357] 'process raft request' (duration: 142.764555ms)","trace[2107429357] 'compare' (duration: 253.010958ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:00.630528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:00.2341Z","time spent":"396.394731ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:00.818195Z","caller":"traceutil/trace.go:171","msg":"trace[1514621147] transaction","detail":"{read_only:false; response_revision:2673; number_of_response:1; }","duration":"181.93866ms","start":"2026-08-26T00:17:00.636226Z","end":"2026-08-26T00:17:00.818164Z","steps":["trace[1514621147] 'process raft request' (duration: 176.499557ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:02.518279Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:03.019277Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:03.519415Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:04.019852Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:04.520207Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:05.021097Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:05.078762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000141457s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:05.078866Z","caller":"traceutil/trace.go:171","msg":"trace[28132871] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000276957s","start":"2026-08-26T00:17:03.078547Z","end":"2026-08-26T00:17:05.078823Z","steps":["trace[28132871] 'agreement among raft nodes before linearized reading' (duration: 2.000175117s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:05.078924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:03.078525Z","time spent":"2.000388496s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:05 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:05.521769Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:06.022434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:06.299764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000403212s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:06.299848Z","caller":"traceutil/trace.go:171","msg":"trace[1668691924] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000539321s","start":"2026-08-26T00:17:04.299289Z","end":"2026-08-26T00:17:06.299828Z","steps":["trace[1668691924] 'agreement among raft nodes before linearized reading' (duration: 2.000436591s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:06.299895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:04.299263Z","time spent":"2.00062199s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:06 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:06.523143Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:07.02349Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:07.079042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999661086s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-26T00:17:07.079116Z","caller":"traceutil/trace.go:171","msg":"trace[1251375049] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999800715s","start":"2026-08-26T00:17:05.0793Z","end":"2026-08-26T00:17:07.0791Z","steps":["trace[1251375049] 'agreement among raft nodes before linearized reading' (duration: 1.999693706s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:07.079157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.079283Z","time spent":"1.999864595s","remote":"127.0.0.1:36910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/26 00:17:07 WARNING: [core] [Server #6] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-26T00:17:07.52394Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:08.024992Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11811710624738553810,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-26T00:17:08.307207Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.895633183s","expected-duration":"1s"} {"level":"info","ts":"2026-08-26T00:17:08.307641Z","caller":"traceutil/trace.go:171","msg":"trace[774552270] transaction","detail":"{read_only:false; response_revision:2679; number_of_response:1; }","duration":"6.89610775s","start":"2026-08-26T00:17:01.411479Z","end":"2026-08-26T00:17:08.307587Z","steps":["trace[774552270] 'process raft request' (duration: 6.895913811s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.307822Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.411453Z","time spent":"6.896280479s","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":854,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-26T00:17:08.315151Z","caller":"traceutil/trace.go:171","msg":"trace[869107155] transaction","detail":"{read_only:false; response_revision:2680; number_of_response:1; }","duration":"6.895351234s","start":"2026-08-26T00:17:01.419776Z","end":"2026-08-26T00:17:08.315127Z","steps":["trace[869107155] 'process raft request' (duration: 6.895159225s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.315402Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:01.419751Z","time spent":"6.895491632s","remote":"127.0.0.1:37064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4659,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:17:08.315524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.297900704s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-08-26T00:17:08.315395Z","caller":"traceutil/trace.go:171","msg":"trace[1103550988] transaction","detail":"{read_only:false; response_revision:2681; number_of_response:1; }","duration":"1.279908888s","start":"2026-08-26T00:17:07.035306Z","end":"2026-08-26T00:17:08.315215Z","steps":["trace[1103550988] 'process raft request' (duration: 1.279740389s)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:17:08.31558Z","caller":"traceutil/trace.go:171","msg":"trace[446073164] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2681; }","duration":"6.298005002s","start":"2026-08-26T00:17:02.017561Z","end":"2026-08-26T00:17:08.315566Z","steps":["trace[446073164] 'agreement among raft nodes before linearized reading' (duration: 6.297832153s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.315642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.017544Z","time spent":"6.298085962s","remote":"127.0.0.1:37078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1390,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"warn","ts":"2026-08-26T00:17:08.315644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:07.035279Z","time spent":"1.280307756s","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:17:08.31535Z","caller":"traceutil/trace.go:171","msg":"trace[331564697] linearizableReadLoop","detail":"{readStateIndex:2786; appliedIndex:2783; }","duration":"6.297495666s","start":"2026-08-26T00:17:02.017605Z","end":"2026-08-26T00:17:08.315101Z","steps":["trace[331564697] 'read index received' (duration: 6.289828545s)","trace[331564697] 'applied index is now lower than readState.Index' (duration: 7.666281ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:17:08.321311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.684836057s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.321372Z","caller":"traceutil/trace.go:171","msg":"trace[2050232077] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:2682; }","duration":"5.684936296s","start":"2026-08-26T00:17:02.636421Z","end":"2026-08-26T00:17:08.321357Z","steps":["trace[2050232077] 'agreement among raft nodes before linearized reading' (duration: 5.684856567s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.32141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.636404Z","time spent":"5.684995126s","remote":"127.0.0.1:56838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.321625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.235328886s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.321679Z","caller":"traceutil/trace.go:171","msg":"trace[780348904] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2682; }","duration":"1.235421406s","start":"2026-08-26T00:17:07.086245Z","end":"2026-08-26T00:17:08.321666Z","steps":["trace[780348904] 'agreement among raft nodes before linearized reading' (duration: 1.235343986s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.321723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:07.086225Z","time spent":"1.235478405s","remote":"127.0.0.1:36902","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-26T00:17:08.321884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.416801278s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.321918Z","caller":"traceutil/trace.go:171","msg":"trace[1639260339] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2682; }","duration":"1.416871557s","start":"2026-08-26T00:17:06.905038Z","end":"2026-08-26T00:17:08.32191Z","steps":["trace[1639260339] 'agreement among raft nodes before linearized reading' (duration: 1.416816588s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.321947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.905018Z","time spent":"1.416921167s","remote":"127.0.0.1:33714","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":2,"response size":31,"request content":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.322094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.146132325s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.322138Z","caller":"traceutil/trace.go:171","msg":"trace[1730922604] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:2682; }","duration":"2.146199475s","start":"2026-08-26T00:17:06.175922Z","end":"2026-08-26T00:17:08.322122Z","steps":["trace[1730922604] 'agreement among raft nodes before linearized reading' (duration: 2.146149095s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.322172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:06.175905Z","time spent":"2.146259765s","remote":"127.0.0.1:37194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":31,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.322307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.485285258s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.322341Z","caller":"traceutil/trace.go:171","msg":"trace[1705751739] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:2682; }","duration":"2.485319508s","start":"2026-08-26T00:17:05.837014Z","end":"2026-08-26T00:17:08.322333Z","steps":["trace[1705751739] 'agreement among raft nodes before linearized reading' (duration: 2.485274168s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.322367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:05.836954Z","time spent":"2.485406448s","remote":"127.0.0.1:37178","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":29,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.322573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.131919888s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:1853"} {"level":"info","ts":"2026-08-26T00:17:08.322806Z","caller":"traceutil/trace.go:171","msg":"trace[988148664] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2682; }","duration":"4.132187967s","start":"2026-08-26T00:17:04.19061Z","end":"2026-08-26T00:17:08.322798Z","steps":["trace[988148664] 'agreement among raft nodes before linearized reading' (duration: 4.131882388s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.322832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:04.190588Z","time spent":"4.132238337s","remote":"127.0.0.1:37032","response type":"/etcdserverpb.KV/Range","request count":0,"request size":73,"response count":1,"response size":1877,"request content":"key:\"/registry/persistentvolumeclaims/openstack/datadir-percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-26T00:17:08.322993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.620196421s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.323028Z","caller":"traceutil/trace.go:171","msg":"trace[1876073067] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:2682; }","duration":"5.6202557s","start":"2026-08-26T00:17:02.702765Z","end":"2026-08-26T00:17:08.32302Z","steps":["trace[1876073067] 'agreement among raft nodes before linearized reading' (duration: 5.62018488s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.323055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.702753Z","time spent":"5.62029514s","remote":"127.0.0.1:37412","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":36,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.323869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.149119725s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.144\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-08-26T00:17:08.323912Z","caller":"traceutil/trace.go:171","msg":"trace[1835355872] range","detail":"{range_begin:/registry/masterleases/162.253.55.144; range_end:; response_count:1; response_revision:2682; }","duration":"6.149190535s","start":"2026-08-26T00:17:02.174713Z","end":"2026-08-26T00:17:08.323903Z","steps":["trace[1835355872] 'agreement among raft nodes before linearized reading' (duration: 6.149090506s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.32394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.174696Z","time spent":"6.149237725s","remote":"127.0.0.1:36932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/162.253.55.144\" "} {"level":"warn","ts":"2026-08-26T00:17:08.324147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.770818967s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-26T00:17:08.324193Z","caller":"traceutil/trace.go:171","msg":"trace[1116295017] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:2682; }","duration":"5.770899777s","start":"2026-08-26T00:17:02.553277Z","end":"2026-08-26T00:17:08.324176Z","steps":["trace[1116295017] 'agreement among raft nodes before linearized reading' (duration: 5.770832657s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.324233Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.553254Z","time spent":"5.770965366s","remote":"127.0.0.1:37118","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":15,"response size":31,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.32435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.031557743s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.324382Z","caller":"traceutil/trace.go:171","msg":"trace[2125166344] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:2682; }","duration":"6.031619843s","start":"2026-08-26T00:17:02.292747Z","end":"2026-08-26T00:17:08.324367Z","steps":["trace[2125166344] 'agreement among raft nodes before linearized reading' (duration: 6.031587333s)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:17:08.324405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:17:02.292729Z","time spent":"6.031671272s","remote":"127.0.0.1:34062","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:17:08.321034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.411627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:17:08.324542Z","caller":"traceutil/trace.go:171","msg":"trace[1523478241] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:2682; }","duration":"168.952069ms","start":"2026-08-26T00:17:08.155567Z","end":"2026-08-26T00:17:08.324519Z","steps":["trace[1523478241] 'agreement among raft nodes before linearized reading' (duration: 165.398368ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:19:36.78278Z","caller":"traceutil/trace.go:171","msg":"trace[1491075261] transaction","detail":"{read_only:false; response_revision:3589; number_of_response:1; }","duration":"345.639052ms","start":"2026-08-26T00:19:36.437107Z","end":"2026-08-26T00:19:36.782746Z","steps":["trace[1491075261] 'process raft request' (duration: 345.432275ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:19:36.783237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:19:36.437085Z","time spent":"345.8657ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:19:36.783841Z","caller":"traceutil/trace.go:171","msg":"trace[966601465] transaction","detail":"{read_only:false; response_revision:3590; number_of_response:1; }","duration":"108.981591ms","start":"2026-08-26T00:19:36.674826Z","end":"2026-08-26T00:19:36.783808Z","steps":["trace[966601465] 'process raft request' (duration: 108.748073ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:20:43.676548Z","caller":"traceutil/trace.go:171","msg":"trace[1527138355] transaction","detail":"{read_only:false; response_revision:4490; number_of_response:1; }","duration":"144.636211ms","start":"2026-08-26T00:20:43.531889Z","end":"2026-08-26T00:20:43.676525Z","steps":["trace[1527138355] 'process raft request' (duration: 139.050646ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:20:45.712869Z","caller":"traceutil/trace.go:171","msg":"trace[209313274] linearizableReadLoop","detail":"{readStateIndex:4718; appliedIndex:4717; }","duration":"105.478718ms","start":"2026-08-26T00:20:45.607364Z","end":"2026-08-26T00:20:45.712843Z","steps":["trace[209313274] 'read index received' (duration: 6.627437ms)","trace[209313274] 'applied index is now lower than readState.Index' (duration: 98.850351ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:20:45.712887Z","caller":"traceutil/trace.go:171","msg":"trace[2038518565] transaction","detail":"{read_only:false; response_revision:4538; number_of_response:1; }","duration":"115.579092ms","start":"2026-08-26T00:20:45.597271Z","end":"2026-08-26T00:20:45.71285Z","steps":["trace[2038518565] 'process raft request' (duration: 16.735642ms)","trace[2038518565] 'compare' (duration: 98.705867ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:20:45.713266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.874877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12210"} {"level":"info","ts":"2026-08-26T00:20:45.713313Z","caller":"traceutil/trace.go:171","msg":"trace[1593846756] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4538; }","duration":"105.942688ms","start":"2026-08-26T00:20:45.60736Z","end":"2026-08-26T00:20:45.713302Z","steps":["trace[1593846756] 'agreement among raft nodes before linearized reading' (duration: 105.686513ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:20:51.230175Z","caller":"traceutil/trace.go:171","msg":"trace[242971917] transaction","detail":"{read_only:false; response_revision:4661; number_of_response:1; }","duration":"191.033883ms","start":"2026-08-26T00:20:51.039119Z","end":"2026-08-26T00:20:51.230152Z","steps":["trace[242971917] 'process raft request' (duration: 190.88926ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:20:51.239336Z","caller":"traceutil/trace.go:171","msg":"trace[1574037834] linearizableReadLoop","detail":"{readStateIndex:4845; appliedIndex:4843; }","duration":"114.225269ms","start":"2026-08-26T00:20:51.125088Z","end":"2026-08-26T00:20:51.239314Z","steps":["trace[1574037834] 'read index received' (duration: 104.921981ms)","trace[1574037834] 'applied index is now lower than readState.Index' (duration: 9.301968ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:20:51.239414Z","caller":"traceutil/trace.go:171","msg":"trace[1841882367] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"197.845137ms","start":"2026-08-26T00:20:51.041527Z","end":"2026-08-26T00:20:51.239372Z","steps":["trace[1841882367] 'process raft request' (duration: 197.665433ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:20:51.25503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.916972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-08-26T00:20:51.255098Z","caller":"traceutil/trace.go:171","msg":"trace[881604896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4662; }","duration":"130.034036ms","start":"2026-08-26T00:20:51.125046Z","end":"2026-08-26T00:20:51.25508Z","steps":["trace[881604896] 'agreement among raft nodes before linearized reading' (duration: 114.368852ms)","trace[881604896] 'range keys from in-memory index tree' (duration: 15.493599ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:03.806166Z","caller":"traceutil/trace.go:171","msg":"trace[1387364007] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"542.000598ms","start":"2026-08-26T00:21:03.264142Z","end":"2026-08-26T00:21:03.806143Z","steps":["trace[1387364007] 'process raft request' (duration: 541.821925ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.806387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.264117Z","time spent":"542.118891ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-26T00:21:03.847545Z","caller":"traceutil/trace.go:171","msg":"trace[1059140382] linearizableReadLoop","detail":"{readStateIndex:5013; appliedIndex:5011; }","duration":"534.153545ms","start":"2026-08-26T00:21:03.313367Z","end":"2026-08-26T00:21:03.84752Z","steps":["trace[1059140382] 'read index received' (duration: 492.699334ms)","trace[1059140382] 'applied index is now lower than readState.Index' (duration: 41.453251ms)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:21:03.847709Z","caller":"traceutil/trace.go:171","msg":"trace[60849280] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"575.833621ms","start":"2026-08-26T00:21:03.271864Z","end":"2026-08-26T00:21:03.847698Z","steps":["trace[60849280] 'process raft request' (duration: 575.461844ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.847834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.271839Z","time spent":"575.905763ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:21:03.848037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.523245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-26T00:21:03.848073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.75573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"warn","ts":"2026-08-26T00:21:03.848145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.559373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-26T00:21:03.848138Z","caller":"traceutil/trace.go:171","msg":"trace[1819953577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4823; }","duration":"528.683908ms","start":"2026-08-26T00:21:03.319429Z","end":"2026-08-26T00:21:03.848112Z","steps":["trace[1819953577] 'agreement among raft nodes before linearized reading' (duration: 528.391292ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.848063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.443805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-08-26T00:21:03.84821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.319409Z","time spent":"528.78629ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:21:03.848233Z","caller":"traceutil/trace.go:171","msg":"trace[1782351410] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4823; }","duration":"105.655358ms","start":"2026-08-26T00:21:03.742565Z","end":"2026-08-26T00:21:03.848221Z","steps":["trace[1782351410] 'agreement among raft nodes before linearized reading' (duration: 105.384253ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.848094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.325364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-26T00:21:03.848412Z","caller":"traceutil/trace.go:171","msg":"trace[592482075] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4823; }","duration":"310.66294ms","start":"2026-08-26T00:21:03.537727Z","end":"2026-08-26T00:21:03.84839Z","steps":["trace[592482075] 'agreement among raft nodes before linearized reading' (duration: 310.269333ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.848437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.26785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-26T00:21:03.848553Z","caller":"traceutil/trace.go:171","msg":"trace[2010643703] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:4823; }","duration":"481.431433ms","start":"2026-08-26T00:21:03.367105Z","end":"2026-08-26T00:21:03.848536Z","steps":["trace[2010643703] 'agreement among raft nodes before linearized reading' (duration: 480.898363ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:21:03.848561Z","caller":"traceutil/trace.go:171","msg":"trace[1059259008] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4823; }","duration":"534.859909ms","start":"2026-08-26T00:21:03.313317Z","end":"2026-08-26T00:21:03.848176Z","steps":["trace[1059259008] 'agreement among raft nodes before linearized reading' (duration: 534.479022ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.848612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.367084Z","time spent":"481.511054ms","remote":"127.0.0.1:36968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":543,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:21:03.848342Z","caller":"traceutil/trace.go:171","msg":"trace[2015512516] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4823; }","duration":"129.858952ms","start":"2026-08-26T00:21:03.718266Z","end":"2026-08-26T00:21:03.848125Z","steps":["trace[2015512516] 'agreement among raft nodes before linearized reading' (duration: 129.659968ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:21:03.848635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.537675Z","time spent":"310.890315ms","remote":"127.0.0.1:37112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-08-26T00:21:03.848791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:21:03.313299Z","time spent":"535.477791ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-26T00:21:41.095703Z","caller":"traceutil/trace.go:171","msg":"trace[1952646790] transaction","detail":"{read_only:false; response_revision:5182; number_of_response:1; }","duration":"196.739742ms","start":"2026-08-26T00:21:40.898937Z","end":"2026-08-26T00:21:41.095677Z","steps":["trace[1952646790] 'process raft request' (duration: 196.482368ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:29.211299Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2828} {"level":"info","ts":"2026-08-26T00:22:29.283346Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2828,"took":"70.529957ms","hash":3293087874,"current-db-size-bytes":55320576,"current-db-size":"55 MB","current-db-size-in-use-bytes":30400512,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-26T00:22:29.2834Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3293087874,"revision":2828,"compact-revision":-1} {"level":"info","ts":"2026-08-26T00:22:57.094103Z","caller":"traceutil/trace.go:171","msg":"trace[1503310404] transaction","detail":"{read_only:false; response_revision:5698; number_of_response:1; }","duration":"360.490167ms","start":"2026-08-26T00:22:56.733586Z","end":"2026-08-26T00:22:57.094077Z","steps":["trace[1503310404] 'process raft request' (duration: 358.671291ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:57.094189Z","caller":"traceutil/trace.go:171","msg":"trace[592572679] linearizableReadLoop","detail":"{readStateIndex:5914; appliedIndex:5913; }","duration":"316.075716ms","start":"2026-08-26T00:22:56.778093Z","end":"2026-08-26T00:22:57.094169Z","steps":["trace[592572679] 'read index received' (duration: 314.178779ms)","trace[592572679] 'applied index is now lower than readState.Index' (duration: 1.894907ms)"],"step_count":2} {"level":"warn","ts":"2026-08-26T00:22:57.094236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:56.733566Z","time spent":"360.598919ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:22:57.0944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.403712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T00:22:57.094431Z","caller":"traceutil/trace.go:171","msg":"trace[203533535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5699; }","duration":"270.437042ms","start":"2026-08-26T00:22:56.823987Z","end":"2026-08-26T00:22:57.094424Z","steps":["trace[203533535] 'agreement among raft nodes before linearized reading' (duration: 270.334702ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.094383Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.262698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-26T00:22:57.094508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.653936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-26T00:22:57.094529Z","caller":"traceutil/trace.go:171","msg":"trace[238147498] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:5699; }","duration":"316.47746ms","start":"2026-08-26T00:22:56.778029Z","end":"2026-08-26T00:22:57.094506Z","steps":["trace[238147498] 'agreement among raft nodes before linearized reading' (duration: 316.263117ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.094418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.160599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:22:57.094614Z","caller":"traceutil/trace.go:171","msg":"trace[2133816455] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5699; }","duration":"310.393053ms","start":"2026-08-26T00:22:56.78421Z","end":"2026-08-26T00:22:57.094603Z","steps":["trace[2133816455] 'agreement among raft nodes before linearized reading' (duration: 310.105219ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.094643Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:56.784195Z","time spent":"310.442093ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-26T00:22:57.094643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.916626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-26T00:22:57.094625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:56.778005Z","time spent":"316.609712ms","remote":"127.0.0.1:37240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":2,"response size":31,"request content":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true "} {"level":"info","ts":"2026-08-26T00:22:57.094675Z","caller":"traceutil/trace.go:171","msg":"trace[1086105127] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5699; }","duration":"294.971607ms","start":"2026-08-26T00:22:56.799695Z","end":"2026-08-26T00:22:57.094666Z","steps":["trace[1086105127] 'agreement among raft nodes before linearized reading' (duration: 294.890356ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:57.094563Z","caller":"traceutil/trace.go:171","msg":"trace[568504951] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5699; }","duration":"270.725687ms","start":"2026-08-26T00:22:56.823825Z","end":"2026-08-26T00:22:57.09455Z","steps":["trace[568504951] 'agreement among raft nodes before linearized reading' (duration: 270.618285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.09479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.67681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-26T00:22:57.094813Z","caller":"traceutil/trace.go:171","msg":"trace[39400599] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5699; }","duration":"162.72696ms","start":"2026-08-26T00:22:56.93208Z","end":"2026-08-26T00:22:57.094807Z","steps":["trace[39400599] 'agreement among raft nodes before linearized reading' (duration: 162.672229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.094816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.098151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-26T00:22:57.094839Z","caller":"traceutil/trace.go:171","msg":"trace[885915360] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5699; }","duration":"268.138072ms","start":"2026-08-26T00:22:56.826693Z","end":"2026-08-26T00:22:57.094831Z","steps":["trace[885915360] 'agreement among raft nodes before linearized reading' (duration: 268.087512ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:57.094543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.281181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:22:57.094935Z","caller":"traceutil/trace.go:171","msg":"trace[1362395898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5699; }","duration":"272.713505ms","start":"2026-08-26T00:22:56.822211Z","end":"2026-08-26T00:22:57.094924Z","steps":["trace[1362395898] 'agreement among raft nodes before linearized reading' (duration: 272.245471ms)"],"step_count":1} {"level":"info","ts":"2026-08-26T00:22:59.558981Z","caller":"traceutil/trace.go:171","msg":"trace[1457912363] linearizableReadLoop","detail":"{readStateIndex:5925; appliedIndex:5924; }","duration":"453.097458ms","start":"2026-08-26T00:22:59.105861Z","end":"2026-08-26T00:22:59.558958Z","steps":["trace[1457912363] 'read index received' (duration: 452.902766ms)","trace[1457912363] 'applied index is now lower than readState.Index' (duration: 193.852µs)"],"step_count":2} {"level":"info","ts":"2026-08-26T00:22:59.559099Z","caller":"traceutil/trace.go:171","msg":"trace[671315754] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"458.916673ms","start":"2026-08-26T00:22:59.100169Z","end":"2026-08-26T00:22:59.559086Z","steps":["trace[671315754] 'process raft request' (duration: 458.6574ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.559202Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.100144Z","time spent":"458.979153ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-26T00:22:59.559385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.554055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-26T00:22:59.559443Z","caller":"traceutil/trace.go:171","msg":"trace[742131514] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5708; }","duration":"449.616886ms","start":"2026-08-26T00:22:59.109806Z","end":"2026-08-26T00:22:59.559423Z","steps":["trace[742131514] 'agreement among raft nodes before linearized reading' (duration: 449.467084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.559475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.109769Z","time spent":"449.699396ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-26T00:22:59.559648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.760653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-08-26T00:22:59.559764Z","caller":"traceutil/trace.go:171","msg":"trace[2135666221] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5708; }","duration":"453.917846ms","start":"2026-08-26T00:22:59.105827Z","end":"2026-08-26T00:22:59.559745Z","steps":["trace[2135666221] 'agreement among raft nodes before linearized reading' (duration: 453.719073ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.560059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.105815Z","time spent":"454.235479ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":596,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:22:59.55967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.853938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-08-26T00:22:59.561031Z","caller":"traceutil/trace.go:171","msg":"trace[648245312] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5708; }","duration":"451.12061ms","start":"2026-08-26T00:22:59.109749Z","end":"2026-08-26T00:22:59.560869Z","steps":["trace[648245312] 'agreement among raft nodes before linearized reading' (duration: 449.564595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.559667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.860109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-26T00:22:59.561099Z","caller":"traceutil/trace.go:171","msg":"trace[861901129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5708; }","duration":"451.301452ms","start":"2026-08-26T00:22:59.10978Z","end":"2026-08-26T00:22:59.561082Z","steps":["trace[861901129] 'agreement among raft nodes before linearized reading' (duration: 449.810967ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.559845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.585885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-08-26T00:22:59.561171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.109768Z","time spent":"451.380192ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-26T00:22:59.561207Z","caller":"traceutil/trace.go:171","msg":"trace[1164504327] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5708; }","duration":"451.988837ms","start":"2026-08-26T00:22:59.109183Z","end":"2026-08-26T00:22:59.561172Z","steps":["trace[1164504327] 'agreement among raft nodes before linearized reading' (duration: 450.492604ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.561178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.109736Z","time spent":"451.348592ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-26T00:22:59.561276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.10916Z","time spent":"452.099039ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-26T00:22:59.559869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.788977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-08-26T00:22:59.560002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.181272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-08-26T00:22:59.561538Z","caller":"traceutil/trace.go:171","msg":"trace[1392693039] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5708; }","duration":"451.731286ms","start":"2026-08-26T00:22:59.109795Z","end":"2026-08-26T00:22:59.561527Z","steps":["trace[1392693039] 'agreement among raft nodes before linearized reading' (duration: 450.125471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.561602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.109788Z","time spent":"451.799226ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-26T00:22:59.561454Z","caller":"traceutil/trace.go:171","msg":"trace[955301280] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5708; }","duration":"452.398372ms","start":"2026-08-26T00:22:59.109037Z","end":"2026-08-26T00:22:59.561435Z","steps":["trace[955301280] 'agreement among raft nodes before linearized reading' (duration: 450.229031ms)"],"step_count":1} {"level":"warn","ts":"2026-08-26T00:22:59.561825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-26T00:22:59.109024Z","time spent":"452.789535ms","remote":"127.0.0.1:37146","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-08-26T00:27:29.232719Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5563} {"level":"info","ts":"2026-08-26T00:27:29.315598Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5563,"took":"81.844902ms","hash":246413232,"current-db-size-bytes":55320576,"current-db-size":"55 MB","current-db-size-in-use-bytes":18386944,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-26T00:27:29.315718Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":246413232,"revision":5563,"compact-revision":2828}