{"level":"warn","ts":"2026-07-16T00:16:38.382748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:16:38.382824Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.138: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://199.204.45.138:2380","--initial-cluster=instance=https://199.204.45.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.138: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-07-16T00:16:38.382882Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-16T00:16:38.382901Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-16T00:16:38.382922Z","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-07-16T00:16:38.383502Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"]} {"level":"info","ts":"2026-07-16T00:16:38.383571Z","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://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.138: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-07-16T00:16:38.388936Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.146397ms"} {"level":"info","ts":"2026-07-16T00:16:38.397947Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"f3d52791b64f7591","cluster-id":"90076b89649fe294"} {"level":"info","ts":"2026-07-16T00:16:38.398107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=()"} {"level":"info","ts":"2026-07-16T00:16:38.398159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 0"} {"level":"info","ts":"2026-07-16T00:16:38.398181Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft f3d52791b64f7591 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-16T00:16:38.398206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became follower at term 1"} {"level":"info","ts":"2026-07-16T00:16:38.398285Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"warn","ts":"2026-07-16T00:16:38.405715Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-16T00:16:38.407126Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-16T00:16:38.408307Z","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-07-16T00:16:38.410119Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"f3d52791b64f7591","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-16T00:16:38.411425Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"f3d52791b64f7591","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-16T00:16:38.411504Z","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-07-16T00:16:38.413391Z","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-07-16T00:16:38.41344Z","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-07-16T00:16:38.41361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 switched to configuration voters=(17569993028038260113)"} {"level":"info","ts":"2026-07-16T00:16:38.420074Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","added-peer-id":"f3d52791b64f7591","added-peer-peer-urls":["https://199.204.45.138:2380"]} {"level":"info","ts":"2026-07-16T00:16:38.420765Z","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-07-16T00:16:38.420944Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"f3d52791b64f7591","initial-advertise-peer-urls":["https://199.204.45.138:2380"],"listen-peer-urls":["https://199.204.45.138:2380"],"advertise-client-urls":["https://199.204.45.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-16T00:16:38.420974Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-16T00:16:38.421075Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-16T00:16:38.421094Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.138:2380"} {"level":"info","ts":"2026-07-16T00:16:39.398679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 is starting a new election at term 1"} {"level":"info","ts":"2026-07-16T00:16:39.398776Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-16T00:16:39.399123Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgPreVoteResp from f3d52791b64f7591 at term 1"} {"level":"info","ts":"2026-07-16T00:16:39.399265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became candidate at term 2"} {"level":"info","ts":"2026-07-16T00:16:39.399303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 received MsgVoteResp from f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-16T00:16:39.399465Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"f3d52791b64f7591 became leader at term 2"} {"level":"info","ts":"2026-07-16T00:16:39.399578Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: f3d52791b64f7591 elected leader f3d52791b64f7591 at term 2"} {"level":"info","ts":"2026-07-16T00:16:39.405773Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"f3d52791b64f7591","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.138:2379]}","request-path":"/0/members/f3d52791b64f7591/attributes","cluster-id":"90076b89649fe294","publish-timeout":"7s"} {"level":"info","ts":"2026-07-16T00:16:39.406292Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:16:39.406782Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:39.41128Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-16T00:16:39.411433Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.138:2379"} {"level":"info","ts":"2026-07-16T00:16:39.411525Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"90076b89649fe294","local-member-id":"f3d52791b64f7591","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:39.411605Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:39.41164Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-16T00:16:39.413197Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-16T00:16:39.413236Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-16T00:16:39.413995Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-16T00:16:40.864899Z","caller":"traceutil/trace.go:171","msg":"trace[1133835883] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"165.964424ms","start":"2026-07-16T00:16:40.698909Z","end":"2026-07-16T00:16:40.864873Z","steps":["trace[1133835883] 'process raft request' (duration: 165.740382ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:41.210848Z","caller":"traceutil/trace.go:171","msg":"trace[801995786] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"511.788108ms","start":"2026-07-16T00:16:40.698996Z","end":"2026-07-16T00:16:41.210785Z","steps":["trace[801995786] 'process raft request' (duration: 511.573435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.211584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.698984Z","time spent":"512.01767ms","remote":"127.0.0.1:37710","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:16:41.211958Z","caller":"traceutil/trace.go:171","msg":"trace[1007329215] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"446.587723ms","start":"2026-07-16T00:16:40.765346Z","end":"2026-07-16T00:16:41.211934Z","steps":["trace[1007329215] 'process raft request' (duration: 445.37163ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.212118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765333Z","time spent":"446.694904ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:41.212134Z","caller":"traceutil/trace.go:171","msg":"trace[2123411974] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"446.736304ms","start":"2026-07-16T00:16:40.765382Z","end":"2026-07-16T00:16:41.212119Z","steps":["trace[2123411974] 'process raft request' (duration: 446.520482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.212252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765368Z","time spent":"446.851135ms","remote":"127.0.0.1:38104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:41.212298Z","caller":"traceutil/trace.go:171","msg":"trace[2026759622] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"446.722653ms","start":"2026-07-16T00:16:40.765541Z","end":"2026-07-16T00:16:41.212264Z","steps":["trace[2026759622] 'process raft request' (duration: 446.545351ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:41.212364Z","caller":"traceutil/trace.go:171","msg":"trace[1818750108] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"504.387367ms","start":"2026-07-16T00:16:40.707933Z","end":"2026-07-16T00:16:41.21232Z","steps":["trace[1818750108] 'read index received' (duration: 156.648693ms)","trace[1818750108] 'applied index is now lower than readState.Index' (duration: 347.736624ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:16:41.212507Z","caller":"traceutil/trace.go:171","msg":"trace[1341606783] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"447.031627ms","start":"2026-07-16T00:16:40.765461Z","end":"2026-07-16T00:16:41.212493Z","steps":["trace[1341606783] 'process raft request' (duration: 446.578232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.212578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765442Z","time spent":"447.097888ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:41.212443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765498Z","time spent":"446.882516ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:41.212586Z","caller":"traceutil/trace.go:171","msg":"trace[1410781595] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"437.165851ms","start":"2026-07-16T00:16:40.77539Z","end":"2026-07-16T00:16:41.212556Z","steps":["trace[1410781595] 'process raft request' (duration: 436.822337ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:41.212601Z","caller":"traceutil/trace.go:171","msg":"trace[1333817563] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"423.24214ms","start":"2026-07-16T00:16:40.789346Z","end":"2026-07-16T00:16:41.212588Z","steps":["trace[1333817563] 'process raft request' (duration: 422.918236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.212658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.77538Z","time spent":"437.244951ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:41.212881Z","caller":"traceutil/trace.go:171","msg":"trace[940058729] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"447.207869ms","start":"2026-07-16T00:16:40.765655Z","end":"2026-07-16T00:16:41.212862Z","steps":["trace[940058729] 'process raft request' (duration: 446.477871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.212982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765644Z","time spent":"447.29448ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:41.213046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.789318Z","time spent":"423.32426ms","remote":"127.0.0.1:37892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-16T00:16:41.213073Z","caller":"traceutil/trace.go:171","msg":"trace[1087239889] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"447.389631ms","start":"2026-07-16T00:16:40.765673Z","end":"2026-07-16T00:16:41.213063Z","steps":["trace[1087239889] 'process raft request' (duration: 446.490611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.213124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765664Z","time spent":"447.431301ms","remote":"127.0.0.1:38174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-16T00:16:41.213138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.593403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:41.213211Z","caller":"traceutil/trace.go:171","msg":"trace[391335498] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"460.718415ms","start":"2026-07-16T00:16:40.752481Z","end":"2026-07-16T00:16:41.213199Z","steps":["trace[391335498] 'agreement among raft nodes before linearized reading' (duration: 460.326151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.213186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"505.213936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:16:41.213261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.378941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:16:41.213285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.519363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:41.213312Z","caller":"traceutil/trace.go:171","msg":"trace[236780491] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"448.579484ms","start":"2026-07-16T00:16:40.764724Z","end":"2026-07-16T00:16:41.213304Z","steps":["trace[236780491] 'agreement among raft nodes before linearized reading' (duration: 448.542983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.213345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.764706Z","time spent":"448.633994ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213341Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.631946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-16T00:16:41.213354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.228148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:41.213383Z","caller":"traceutil/trace.go:171","msg":"trace[1086750276] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"375.683896ms","start":"2026-07-16T00:16:40.837689Z","end":"2026-07-16T00:16:41.213373Z","steps":["trace[1086750276] 'agreement among raft nodes before linearized reading' (duration: 375.624646ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:41.213384Z","caller":"traceutil/trace.go:171","msg":"trace[1126412239] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"396.270478ms","start":"2026-07-16T00:16:40.817106Z","end":"2026-07-16T00:16:41.213376Z","steps":["trace[1126412239] 'agreement among raft nodes before linearized reading' (duration: 396.229308ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:16:41.213313Z","caller":"traceutil/trace.go:171","msg":"trace[1812919541] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"447.451402ms","start":"2026-07-16T00:16:40.76585Z","end":"2026-07-16T00:16:41.213301Z","steps":["trace[1812919541] 'agreement among raft nodes before linearized reading' (duration: 447.369721ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.213497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.765844Z","time spent":"447.645604ms","remote":"127.0.0.1:37738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"info","ts":"2026-07-16T00:16:41.213326Z","caller":"traceutil/trace.go:171","msg":"trace[1892461558] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"505.367548ms","start":"2026-07-16T00:16:40.707915Z","end":"2026-07-16T00:16:41.213283Z","steps":["trace[1892461558] 'agreement among raft nodes before linearized reading' (duration: 504.729021ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.21361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.929128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.138\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:41.213695Z","caller":"traceutil/trace.go:171","msg":"trace[2110772716] range","detail":"{range_begin:/registry/masterleases/199.204.45.138; range_end:; response_count:0; response_revision:11; }","duration":"449.022229ms","start":"2026-07-16T00:16:40.764659Z","end":"2026-07-16T00:16:41.213681Z","steps":["trace[2110772716] 'agreement among raft nodes before linearized reading' (duration: 448.789736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.213753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.764651Z","time spent":"449.090669ms","remote":"127.0.0.1:37674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":28,"request content":"key:\"/registry/masterleases/199.204.45.138\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.707908Z","time spent":"505.687232ms","remote":"127.0.0.1:38038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":28,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.83768Z","time spent":"375.739117ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.752472Z","time spent":"460.769986ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.817096Z","time spent":"396.359889ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-16T00:16:41.213544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.917497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-16T00:16:41.214212Z","caller":"traceutil/trace.go:171","msg":"trace[1308992839] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"461.643235ms","start":"2026-07-16T00:16:40.752555Z","end":"2026-07-16T00:16:41.214198Z","steps":["trace[1308992839] 'agreement among raft nodes before linearized reading' (duration: 460.876497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:16:41.214262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:16:40.752537Z","time spent":"461.711386ms","remote":"127.0.0.1:37768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":28,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-07-16T00:17:15.42914Z","caller":"traceutil/trace.go:171","msg":"trace[176882648] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"179.647778ms","start":"2026-07-16T00:17:15.249457Z","end":"2026-07-16T00:17:15.429104Z","steps":["trace[176882648] 'process raft request' (duration: 150.674973ms)","trace[176882648] 'compare' (duration: 28.879864ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:15.556232Z","caller":"traceutil/trace.go:171","msg":"trace[213037206] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"306.381926ms","start":"2026-07-16T00:17:15.249833Z","end":"2026-07-16T00:17:15.556215Z","steps":["trace[213037206] 'process raft request' (duration: 302.724597ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:15.556334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-16T00:17:15.249812Z","time spent":"306.469357ms","remote":"127.0.0.1:37896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-16T00:17:15.557572Z","caller":"traceutil/trace.go:171","msg":"trace[295467987] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"238.539465ms","start":"2026-07-16T00:17:15.319003Z","end":"2026-07-16T00:17:15.557543Z","steps":["trace[295467987] 'process raft request' (duration: 237.245475ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:15.562561Z","caller":"traceutil/trace.go:171","msg":"trace[1992202633] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"288.078277ms","start":"2026-07-16T00:17:15.274442Z","end":"2026-07-16T00:17:15.56252Z","steps":["trace[1992202633] 'read index received' (duration: 125.71953ms)","trace[1992202633] 'applied index is now lower than readState.Index' (duration: 162.358307ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:17:15.562931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.135399ms","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-07-16T00:17:15.562983Z","caller":"traceutil/trace.go:171","msg":"trace[438589267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:598; }","duration":"166.219679ms","start":"2026-07-16T00:17:15.39675Z","end":"2026-07-16T00:17:15.56297Z","steps":["trace[438589267] 'agreement among raft nodes before linearized reading' (duration: 166.099108ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:15.563097Z","caller":"traceutil/trace.go:171","msg":"trace[802263489] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"224.807044ms","start":"2026-07-16T00:17:15.338282Z","end":"2026-07-16T00:17:15.563089Z","steps":["trace[802263489] 'process raft request' (duration: 223.306042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:15.563494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.036515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-07-16T00:17:15.56353Z","caller":"traceutil/trace.go:171","msg":"trace[271548425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:598; }","duration":"289.113976ms","start":"2026-07-16T00:17:15.274406Z","end":"2026-07-16T00:17:15.56352Z","steps":["trace[271548425] 'agreement among raft nodes before linearized reading' (duration: 288.334989ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:19.936376Z","caller":"traceutil/trace.go:171","msg":"trace[2123318848] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"159.769512ms","start":"2026-07-16T00:17:19.776581Z","end":"2026-07-16T00:17:19.936351Z","steps":["trace[2123318848] 'process raft request' (duration: 81.402388ms)","trace[2123318848] 'compare' (duration: 77.85289ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:17:20.129645Z","caller":"traceutil/trace.go:171","msg":"trace[1283816018] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"181.677161ms","start":"2026-07-16T00:17:19.947918Z","end":"2026-07-16T00:17:20.129595Z","steps":["trace[1283816018] 'process raft request' (duration: 175.704183ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:20.563139Z","caller":"traceutil/trace.go:171","msg":"trace[1509464214] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"215.800229ms","start":"2026-07-16T00:17:20.347304Z","end":"2026-07-16T00:17:20.563104Z","steps":["trace[1509464214] 'process raft request' (duration: 207.774046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:17:35.128904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.32854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-16T00:17:35.129008Z","caller":"traceutil/trace.go:171","msg":"trace[1165886821] linearizableReadLoop","detail":"{readStateIndex:816; appliedIndex:815; }","duration":"110.576092ms","start":"2026-07-16T00:17:35.018416Z","end":"2026-07-16T00:17:35.128992Z","steps":["trace[1165886821] 'read index received' (duration: 68.873µs)","trace[1165886821] 'applied index is now lower than readState.Index' (duration: 110.506009ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:17:35.129155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.73524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4jfxj\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-07-16T00:17:35.12919Z","caller":"traceutil/trace.go:171","msg":"trace[1698669601] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-4jfxj; range_end:; response_count:1; response_revision:789; }","duration":"110.806733ms","start":"2026-07-16T00:17:35.018373Z","end":"2026-07-16T00:17:35.12918Z","steps":["trace[1698669601] 'agreement among raft nodes before linearized reading' (duration: 110.667487ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:17:35.129255Z","caller":"traceutil/trace.go:171","msg":"trace[1672325109] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"205.222953ms","start":"2026-07-16T00:17:34.924001Z","end":"2026-07-16T00:17:35.129224Z","steps":["trace[1672325109] 'process raft request' (duration: 54.303365ms)","trace[1672325109] 'compare' (duration: 150.163103ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:18:03.905052Z","caller":"traceutil/trace.go:171","msg":"trace[1948846707] linearizableReadLoop","detail":"{readStateIndex:1219; appliedIndex:1218; }","duration":"143.05486ms","start":"2026-07-16T00:18:03.76198Z","end":"2026-07-16T00:18:03.905035Z","steps":["trace[1948846707] 'read index received' (duration: 142.897975ms)","trace[1948846707] 'applied index is now lower than readState.Index' (duration: 156.245µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:18:03.905102Z","caller":"traceutil/trace.go:171","msg":"trace[1048565840] transaction","detail":"{read_only:false; response_revision:1184; number_of_response:1; }","duration":"146.670607ms","start":"2026-07-16T00:18:03.758412Z","end":"2026-07-16T00:18:03.905082Z","steps":["trace[1048565840] 'process raft request' (duration: 146.508151ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:18:03.905221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.223757ms","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-07-16T00:18:03.905241Z","caller":"traceutil/trace.go:171","msg":"trace[147160749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1184; }","duration":"143.275198ms","start":"2026-07-16T00:18:03.76196Z","end":"2026-07-16T00:18:03.905235Z","steps":["trace[147160749] 'agreement among raft nodes before linearized reading' (duration: 143.170495ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:18:03.905319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.23495ms","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-07-16T00:18:03.905367Z","caller":"traceutil/trace.go:171","msg":"trace[994605467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1184; }","duration":"110.293342ms","start":"2026-07-16T00:18:03.795049Z","end":"2026-07-16T00:18:03.905342Z","steps":["trace[994605467] 'agreement among raft nodes before linearized reading' (duration: 110.194069ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:47.904071Z","caller":"traceutil/trace.go:171","msg":"trace[714226844] linearizableReadLoop","detail":"{readStateIndex:1912; appliedIndex:1911; }","duration":"224.453987ms","start":"2026-07-16T00:18:47.679593Z","end":"2026-07-16T00:18:47.904047Z","steps":["trace[714226844] 'read index received' (duration: 161.882807ms)","trace[714226844] 'applied index is now lower than readState.Index' (duration: 62.56943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:18:47.904247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.643332ms","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-07-16T00:18:47.90434Z","caller":"traceutil/trace.go:171","msg":"trace[758590179] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1841; }","duration":"224.777385ms","start":"2026-07-16T00:18:47.679539Z","end":"2026-07-16T00:18:47.904317Z","steps":["trace[758590179] 'agreement among raft nodes before linearized reading' (duration: 224.55253ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:18:47.904273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.10271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-16T00:18:47.904809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.049701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-16T00:18:47.904879Z","caller":"traceutil/trace.go:171","msg":"trace[1884736064] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1841; }","duration":"150.157634ms","start":"2026-07-16T00:18:47.754706Z","end":"2026-07-16T00:18:47.904864Z","steps":["trace[1884736064] 'agreement among raft nodes before linearized reading' (duration: 149.99591ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:47.904627Z","caller":"traceutil/trace.go:171","msg":"trace[2030557405] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1841; }","duration":"155.474138ms","start":"2026-07-16T00:18:47.749102Z","end":"2026-07-16T00:18:47.904576Z","steps":["trace[2030557405] 'agreement among raft nodes before linearized reading' (duration: 155.10001ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:48.010294Z","caller":"traceutil/trace.go:171","msg":"trace[458260617] transaction","detail":"{read_only:false; response_revision:1842; number_of_response:1; }","duration":"101.023686ms","start":"2026-07-16T00:18:47.909244Z","end":"2026-07-16T00:18:48.010268Z","steps":["trace[458260617] 'process raft request' (duration: 100.810511ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:18:48.025669Z","caller":"traceutil/trace.go:171","msg":"trace[2136010844] transaction","detail":"{read_only:false; response_revision:1843; number_of_response:1; }","duration":"115.959481ms","start":"2026-07-16T00:18:47.909682Z","end":"2026-07-16T00:18:48.025642Z","steps":["trace[2136010844] 'process raft request' (duration: 115.643563ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:19:22.268158Z","caller":"traceutil/trace.go:171","msg":"trace[603358693] linearizableReadLoop","detail":"{readStateIndex:2153; appliedIndex:2152; }","duration":"100.721952ms","start":"2026-07-16T00:19:22.167401Z","end":"2026-07-16T00:19:22.268123Z","steps":["trace[603358693] 'read index received' (duration: 100.378796ms)","trace[603358693] 'applied index is now lower than readState.Index' (duration: 341.816µs)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:19:22.268259Z","caller":"traceutil/trace.go:171","msg":"trace[1379808228] transaction","detail":"{read_only:false; response_revision:2075; number_of_response:1; }","duration":"106.111272ms","start":"2026-07-16T00:19:22.162102Z","end":"2026-07-16T00:19:22.268213Z","steps":["trace[1379808228] 'process raft request' (duration: 105.792726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:19:22.268633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.193371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-07-16T00:19:22.269099Z","caller":"traceutil/trace.go:171","msg":"trace[1886897894] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2075; }","duration":"101.68714ms","start":"2026-07-16T00:19:22.167357Z","end":"2026-07-16T00:19:22.269044Z","steps":["trace[1886897894] 'agreement among raft nodes before linearized reading' (duration: 100.945627ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:26.890743Z","caller":"traceutil/trace.go:171","msg":"trace[433990957] linearizableReadLoop","detail":"{readStateIndex:2613; appliedIndex:2612; }","duration":"231.672365ms","start":"2026-07-16T00:20:26.659045Z","end":"2026-07-16T00:20:26.890717Z","steps":["trace[433990957] 'read index received' (duration: 204.219072ms)","trace[433990957] 'applied index is now lower than readState.Index' (duration: 27.452223ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:20:26.890814Z","caller":"traceutil/trace.go:171","msg":"trace[494432765] transaction","detail":"{read_only:false; response_revision:2520; number_of_response:1; }","duration":"261.127117ms","start":"2026-07-16T00:20:26.629663Z","end":"2026-07-16T00:20:26.89079Z","steps":["trace[494432765] 'process raft request' (duration: 233.586703ms)","trace[494432765] 'compare' (duration: 27.369241ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:20:26.890956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.894759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:570"} {"level":"info","ts":"2026-07-16T00:20:26.890993Z","caller":"traceutil/trace.go:171","msg":"trace[1644336706] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2520; }","duration":"231.971329ms","start":"2026-07-16T00:20:26.659009Z","end":"2026-07-16T00:20:26.89098Z","steps":["trace[1644336706] 'agreement among raft nodes before linearized reading' (duration: 231.833057ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:26.896066Z","caller":"traceutil/trace.go:171","msg":"trace[1893188569] transaction","detail":"{read_only:false; response_revision:2521; number_of_response:1; }","duration":"213.242832ms","start":"2026-07-16T00:20:26.6828Z","end":"2026-07-16T00:20:26.896043Z","steps":["trace[1893188569] 'process raft request' (duration: 212.899907ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:26.896208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.606622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-16T00:20:26.896258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.368885ms","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-07-16T00:20:26.896278Z","caller":"traceutil/trace.go:171","msg":"trace[9045757] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2522; }","duration":"140.678903ms","start":"2026-07-16T00:20:26.755567Z","end":"2026-07-16T00:20:26.896246Z","steps":["trace[9045757] 'agreement among raft nodes before linearized reading' (duration: 140.592191ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:26.896325Z","caller":"traceutil/trace.go:171","msg":"trace[911602479] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2522; }","duration":"188.456727ms","start":"2026-07-16T00:20:26.707852Z","end":"2026-07-16T00:20:26.896309Z","steps":["trace[911602479] 'agreement among raft nodes before linearized reading' (duration: 188.277204ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:26.8964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.563881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-16T00:20:26.896455Z","caller":"traceutil/trace.go:171","msg":"trace[717239019] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2522; }","duration":"163.663501ms","start":"2026-07-16T00:20:26.732776Z","end":"2026-07-16T00:20:26.89644Z","steps":["trace[717239019] 'agreement among raft nodes before linearized reading' (duration: 163.518739ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:26.896623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.399425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-16T00:20:26.896607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.581793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1984"} {"level":"warn","ts":"2026-07-16T00:20:26.89664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.872209ms","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-07-16T00:20:26.896659Z","caller":"traceutil/trace.go:171","msg":"trace[710253426] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2522; }","duration":"190.445775ms","start":"2026-07-16T00:20:26.706196Z","end":"2026-07-16T00:20:26.896642Z","steps":["trace[710253426] 'agreement among raft nodes before linearized reading' (duration: 190.375044ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:26.896662Z","caller":"traceutil/trace.go:171","msg":"trace[176259479] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2522; }","duration":"196.659894ms","start":"2026-07-16T00:20:26.699989Z","end":"2026-07-16T00:20:26.896649Z","steps":["trace[176259479] 'agreement among raft nodes before linearized reading' (duration: 196.496882ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:26.896681Z","caller":"traceutil/trace.go:171","msg":"trace[1289900234] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2522; }","duration":"215.93114ms","start":"2026-07-16T00:20:26.680734Z","end":"2026-07-16T00:20:26.896665Z","steps":["trace[1289900234] 'agreement among raft nodes before linearized reading' (duration: 215.831318ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:26.896904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.679117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-16T00:20:26.896955Z","caller":"traceutil/trace.go:171","msg":"trace[350345646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2523; }","duration":"236.767318ms","start":"2026-07-16T00:20:26.660173Z","end":"2026-07-16T00:20:26.89694Z","steps":["trace[350345646] 'agreement among raft nodes before linearized reading' (duration: 236.583475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:20:26.896881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.765293ms","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-07-16T00:20:26.896994Z","caller":"traceutil/trace.go:171","msg":"trace[1384493668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2523; }","duration":"237.896894ms","start":"2026-07-16T00:20:26.659089Z","end":"2026-07-16T00:20:26.896986Z","steps":["trace[1384493668] 'agreement among raft nodes before linearized reading' (duration: 237.020272ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:58.804479Z","caller":"traceutil/trace.go:171","msg":"trace[976076613] transaction","detail":"{read_only:false; response_revision:2698; number_of_response:1; }","duration":"164.222994ms","start":"2026-07-16T00:20:58.640235Z","end":"2026-07-16T00:20:58.804458Z","steps":["trace[976076613] 'process raft request' (duration: 163.878749ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:20:59.114433Z","caller":"traceutil/trace.go:171","msg":"trace[545837436] transaction","detail":"{read_only:false; response_revision:2700; number_of_response:1; }","duration":"101.413252ms","start":"2026-07-16T00:20:59.012988Z","end":"2026-07-16T00:20:59.114401Z","steps":["trace[545837436] 'process raft request' (duration: 79.612121ms)","trace[545837436] 'compare' (duration: 21.671119ms)"],"step_count":2} {"level":"info","ts":"2026-07-16T00:24:48.263984Z","caller":"traceutil/trace.go:171","msg":"trace[595320611] transaction","detail":"{read_only:false; response_revision:4689; number_of_response:1; }","duration":"113.499849ms","start":"2026-07-16T00:24:48.150453Z","end":"2026-07-16T00:24:48.263953Z","steps":["trace[595320611] 'process raft request' (duration: 113.293914ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:24:48.3435Z","caller":"traceutil/trace.go:171","msg":"trace[977738464] linearizableReadLoop","detail":"{readStateIndex:4865; appliedIndex:4863; }","duration":"190.092655ms","start":"2026-07-16T00:24:48.153386Z","end":"2026-07-16T00:24:48.343478Z","steps":["trace[977738464] 'read index received' (duration: 110.326101ms)","trace[977738464] 'applied index is now lower than readState.Index' (duration: 79.765574ms)"],"step_count":2} {"level":"warn","ts":"2026-07-16T00:24:48.344161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.821738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-16T00:24:48.344253Z","caller":"traceutil/trace.go:171","msg":"trace[1193906333] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4690; }","duration":"180.998682ms","start":"2026-07-16T00:24:48.163228Z","end":"2026-07-16T00:24:48.344227Z","steps":["trace[1193906333] 'agreement among raft nodes before linearized reading' (duration: 180.626083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-16T00:24:48.343726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.286849ms","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":"info","ts":"2026-07-16T00:24:48.344498Z","caller":"traceutil/trace.go:171","msg":"trace[2006938187] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:4690; }","duration":"191.14355ms","start":"2026-07-16T00:24:48.153334Z","end":"2026-07-16T00:24:48.344477Z","steps":["trace[2006938187] 'agreement among raft nodes before linearized reading' (duration: 190.257209ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:24:48.343695Z","caller":"traceutil/trace.go:171","msg":"trace[1112172562] transaction","detail":"{read_only:false; response_revision:4690; number_of_response:1; }","duration":"192.471833ms","start":"2026-07-16T00:24:48.151163Z","end":"2026-07-16T00:24:48.343635Z","steps":["trace[1112172562] 'process raft request' (duration: 192.183616ms)"],"step_count":1} {"level":"info","ts":"2026-07-16T00:26:39.490508Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2917} {"level":"info","ts":"2026-07-16T00:26:39.588898Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2917,"took":"97.059126ms","hash":1376305945,"current-db-size-bytes":55013376,"current-db-size":"55 MB","current-db-size-in-use-bytes":30044160,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-16T00:26:39.588953Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1376305945,"revision":2917,"compact-revision":-1} {"level":"info","ts":"2026-07-16T00:31:39.502281Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5652} {"level":"info","ts":"2026-07-16T00:31:39.584012Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5652,"took":"76.065436ms","hash":519622956,"current-db-size-bytes":55013376,"current-db-size":"55 MB","current-db-size-in-use-bytes":18329600,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-16T00:31:39.584107Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":519622956,"revision":5652,"compact-revision":2917}