{"level":"warn","ts":"2026-04-04T10:22:50.488106Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:22:50.488257Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.104: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.19.213.104:2380","--initial-cluster=instance=https://199.19.213.104:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.104:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.104: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-04-04T10:22:50.488338Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-04T10:22:50.488375Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-04-04T10:22:50.48841Z","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-04-04T10:22:50.489131Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"]} {"level":"info","ts":"2026-04-04T10:22:50.489238Z","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":16,"max-cpu-available":16,"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.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.104: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-04-04T10:22:50.49345Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.950132ms"} {"level":"info","ts":"2026-04-04T10:22:50.502873Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8a0c9a032e61a05a","cluster-id":"153e4a99345578f6"} {"level":"info","ts":"2026-04-04T10:22:50.502977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=()"} {"level":"info","ts":"2026-04-04T10:22:50.503002Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 0"} {"level":"info","ts":"2026-04-04T10:22:50.503012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8a0c9a032e61a05a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-04T10:22:50.503021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became follower at term 1"} {"level":"info","ts":"2026-04-04T10:22:50.503106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"warn","ts":"2026-04-04T10:22:50.509635Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-04T10:22:50.512969Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-04T10:22:50.514171Z","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-04-04T10:22:50.515938Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8a0c9a032e61a05a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-04T10:22:50.516149Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8a0c9a032e61a05a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-04T10:22:50.516395Z","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-04-04T10:22:50.516566Z","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-04-04T10:22:50.516594Z","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-04-04T10:22:50.51909Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a switched to configuration voters=(9947495015408312410)"} {"level":"info","ts":"2026-04-04T10:22:50.519955Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","added-peer-id":"8a0c9a032e61a05a","added-peer-peer-urls":["https://199.19.213.104:2380"]} {"level":"info","ts":"2026-04-04T10:22:50.522268Z","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-04-04T10:22:50.522441Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-04-04T10:22:50.522508Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.104:2380"} {"level":"info","ts":"2026-04-04T10:22:50.52258Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8a0c9a032e61a05a","initial-advertise-peer-urls":["https://199.19.213.104:2380"],"listen-peer-urls":["https://199.19.213.104:2380"],"advertise-client-urls":["https://199.19.213.104:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.104:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-04T10:22:50.522646Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-04T10:22:50.804258Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a is starting a new election at term 1"} {"level":"info","ts":"2026-04-04T10:22:50.804319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-04T10:22:50.804357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgPreVoteResp from 8a0c9a032e61a05a at term 1"} {"level":"info","ts":"2026-04-04T10:22:50.804377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became candidate at term 2"} {"level":"info","ts":"2026-04-04T10:22:50.804392Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a received MsgVoteResp from 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-04-04T10:22:50.804416Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8a0c9a032e61a05a became leader at term 2"} {"level":"info","ts":"2026-04-04T10:22:50.804432Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8a0c9a032e61a05a elected leader 8a0c9a032e61a05a at term 2"} {"level":"info","ts":"2026-04-04T10:22:50.806012Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8a0c9a032e61a05a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.104:2379]}","request-path":"/0/members/8a0c9a032e61a05a/attributes","cluster-id":"153e4a99345578f6","publish-timeout":"7s"} {"level":"info","ts":"2026-04-04T10:22:50.806111Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:22:50.806306Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:22:50.806331Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-04T10:22:50.807536Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"153e4a99345578f6","local-member-id":"8a0c9a032e61a05a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:22:50.80765Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:22:50.807687Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-04T10:22:50.806343Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-04T10:22:50.807795Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-04T10:22:50.810929Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-04T10:22:50.817292Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.104:2379"} {"level":"info","ts":"2026-04-04T10:22:55.269279Z","caller":"traceutil/trace.go:171","msg":"trace[255021774] transaction","detail":"{read_only:false; response_revision:246; number_of_response:1; }","duration":"110.788523ms","start":"2026-04-04T10:22:55.158465Z","end":"2026-04-04T10:22:55.269253Z","steps":["trace[255021774] 'process raft request' (duration: 110.62739ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:22:55.354609Z","caller":"traceutil/trace.go:171","msg":"trace[1490836645] transaction","detail":"{read_only:false; response_revision:247; number_of_response:1; }","duration":"195.066534ms","start":"2026-04-04T10:22:55.159506Z","end":"2026-04-04T10:22:55.354573Z","steps":["trace[1490836645] 'process raft request' (duration: 188.817056ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:02.237481Z","caller":"traceutil/trace.go:171","msg":"trace[1438586670] linearizableReadLoop","detail":"{readStateIndex:410; appliedIndex:409; }","duration":"122.456314ms","start":"2026-04-04T10:23:02.114992Z","end":"2026-04-04T10:23:02.237448Z","steps":["trace[1438586670] 'read index received' (duration: 122.218649ms)","trace[1438586670] 'applied index is now lower than readState.Index' (duration: 236.255µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:02.237611Z","caller":"traceutil/trace.go:171","msg":"trace[2037050839] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"132.853367ms","start":"2026-04-04T10:23:02.10471Z","end":"2026-04-04T10:23:02.237563Z","steps":["trace[2037050839] 'process raft request' (duration: 132.543211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:02.237664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.650298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-04-04T10:23:02.237739Z","caller":"traceutil/trace.go:171","msg":"trace[1981419103] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:397; }","duration":"122.75409ms","start":"2026-04-04T10:23:02.114973Z","end":"2026-04-04T10:23:02.237727Z","steps":["trace[1981419103] 'agreement among raft nodes before linearized reading' (duration: 122.610757ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.586433Z","caller":"traceutil/trace.go:171","msg":"trace[391032235] transaction","detail":"{read_only:false; number_of_response:0; response_revision:530; }","duration":"113.975298ms","start":"2026-04-04T10:23:21.472413Z","end":"2026-04-04T10:23:21.586388Z","steps":["trace[391032235] 'process raft request' (duration: 113.732233ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.586762Z","caller":"traceutil/trace.go:171","msg":"trace[1117580299] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"114.161331ms","start":"2026-04-04T10:23:21.47256Z","end":"2026-04-04T10:23:21.586721Z","steps":["trace[1117580299] 'process raft request' (duration: 113.863765ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.587016Z","caller":"traceutil/trace.go:171","msg":"trace[1379997753] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"110.405933ms","start":"2026-04-04T10:23:21.476601Z","end":"2026-04-04T10:23:21.587007Z","steps":["trace[1379997753] 'process raft request' (duration: 110.037326ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.587442Z","caller":"traceutil/trace.go:171","msg":"trace[385595239] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"110.781871ms","start":"2026-04-04T10:23:21.476643Z","end":"2026-04-04T10:23:21.587425Z","steps":["trace[385595239] 'process raft request' (duration: 110.27051ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.587421Z","caller":"traceutil/trace.go:171","msg":"trace[1903609860] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"110.698539ms","start":"2026-04-04T10:23:21.4767Z","end":"2026-04-04T10:23:21.587399Z","steps":["trace[1903609860] 'process raft request' (duration: 110.614937ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.596209Z","caller":"traceutil/trace.go:171","msg":"trace[1603520529] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"115.842585ms","start":"2026-04-04T10:23:21.480317Z","end":"2026-04-04T10:23:21.59616Z","steps":["trace[1603520529] 'process raft request' (duration: 115.560029ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.84153Z","caller":"traceutil/trace.go:171","msg":"trace[925860061] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"111.162148ms","start":"2026-04-04T10:23:21.730343Z","end":"2026-04-04T10:23:21.841505Z","steps":["trace[925860061] 'process raft request' (duration: 93.096396ms)","trace[925860061] 'compare' (duration: 17.860588ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:21.842049Z","caller":"traceutil/trace.go:171","msg":"trace[1465029369] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"110.73497ms","start":"2026-04-04T10:23:21.731305Z","end":"2026-04-04T10:23:21.84204Z","steps":["trace[1465029369] 'process raft request' (duration: 110.294251ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.842218Z","caller":"traceutil/trace.go:171","msg":"trace[1793745831] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"107.422862ms","start":"2026-04-04T10:23:21.734739Z","end":"2026-04-04T10:23:21.842162Z","steps":["trace[1793745831] 'process raft request' (duration: 107.153216ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:21.843355Z","caller":"traceutil/trace.go:171","msg":"trace[666181571] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"104.333478ms","start":"2026-04-04T10:23:21.738987Z","end":"2026-04-04T10:23:21.84332Z","steps":["trace[666181571] 'process raft request' (duration: 103.035872ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:27.777485Z","caller":"traceutil/trace.go:171","msg":"trace[271479036] linearizableReadLoop","detail":"{readStateIndex:643; appliedIndex:642; }","duration":"137.922729ms","start":"2026-04-04T10:23:27.639529Z","end":"2026-04-04T10:23:27.777452Z","steps":["trace[271479036] 'read index received' (duration: 137.702925ms)","trace[271479036] 'applied index is now lower than readState.Index' (duration: 218.594µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:27.777551Z","caller":"traceutil/trace.go:171","msg":"trace[488574191] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"247.572019ms","start":"2026-04-04T10:23:27.52995Z","end":"2026-04-04T10:23:27.777522Z","steps":["trace[488574191] 'process raft request' (duration: 247.12404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:27.777775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.228776ms","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-04-04T10:23:27.777846Z","caller":"traceutil/trace.go:171","msg":"trace[1348305293] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:620; }","duration":"138.37515ms","start":"2026-04-04T10:23:27.63945Z","end":"2026-04-04T10:23:27.777825Z","steps":["trace[1348305293] 'agreement among raft nodes before linearized reading' (duration: 138.165276ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:23:27.960316Z","caller":"traceutil/trace.go:171","msg":"trace[1282576619] linearizableReadLoop","detail":"{readStateIndex:644; appliedIndex:643; }","duration":"182.697151ms","start":"2026-04-04T10:23:27.777588Z","end":"2026-04-04T10:23:27.960285Z","steps":["trace[1282576619] 'read index received' (duration: 181.700011ms)","trace[1282576619] 'applied index is now lower than readState.Index' (duration: 995.64µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:23:27.960401Z","caller":"traceutil/trace.go:171","msg":"trace[1679063455] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"203.783266ms","start":"2026-04-04T10:23:27.756586Z","end":"2026-04-04T10:23:27.96037Z","steps":["trace[1679063455] 'process raft request' (duration: 202.96851ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:27.960566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.524119ms","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-04-04T10:23:27.960623Z","caller":"traceutil/trace.go:171","msg":"trace[96970221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"319.651701ms","start":"2026-04-04T10:23:27.640959Z","end":"2026-04-04T10:23:27.960611Z","steps":["trace[96970221] 'agreement among raft nodes before linearized reading' (duration: 319.436357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:27.960666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:27.640938Z","time spent":"319.718843ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-04T10:23:27.964299Z","caller":"traceutil/trace.go:171","msg":"trace[816206329] transaction","detail":"{read_only:false; response_revision:622; number_of_response:1; }","duration":"181.71273ms","start":"2026-04-04T10:23:27.782563Z","end":"2026-04-04T10:23:27.964275Z","steps":["trace[816206329] 'process raft request' (duration: 181.624128ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:28.86716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"671.10731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:23:28.867364Z","caller":"traceutil/trace.go:171","msg":"trace[1637285949] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"671.873725ms","start":"2026-04-04T10:23:28.195452Z","end":"2026-04-04T10:23:28.867326Z","steps":["trace[1637285949] 'range keys from in-memory index tree' (duration: 671.026519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:28.867548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:28.195398Z","time spent":"672.050499ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-04T10:23:28.867866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"460.35183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-04T10:23:28.8682Z","caller":"traceutil/trace.go:171","msg":"trace[1041641472] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:624; }","duration":"460.519623ms","start":"2026-04-04T10:23:28.407442Z","end":"2026-04-04T10:23:28.867961Z","steps":["trace[1041641472] 'range keys from in-memory index tree' (duration: 460.135356ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:28.868303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:28.407418Z","time spent":"460.836739ms","remote":"127.0.0.1:43992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-04T10:23:28.877191Z","caller":"traceutil/trace.go:171","msg":"trace[164522637] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"396.214189ms","start":"2026-04-04T10:23:28.480949Z","end":"2026-04-04T10:23:28.877163Z","steps":["trace[164522637] 'process raft request' (duration: 379.973556ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:23:28.877735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:23:28.480916Z","time spent":"396.343631ms","remote":"127.0.0.1:43816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:23:44.342503Z","caller":"traceutil/trace.go:171","msg":"trace[2105165959] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"155.509892ms","start":"2026-04-04T10:23:44.18696Z","end":"2026-04-04T10:23:44.34247Z","steps":["trace[2105165959] 'process raft request' (duration: 154.221935ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:58.50578Z","caller":"traceutil/trace.go:171","msg":"trace[806776258] transaction","detail":"{read_only:false; response_revision:1855; number_of_response:1; }","duration":"400.683773ms","start":"2026-04-04T10:24:58.105055Z","end":"2026-04-04T10:24:58.505739Z","steps":["trace[806776258] 'process raft request' (duration: 327.454379ms)","trace[806776258] 'compare' (duration: 72.958528ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:24:58.506098Z","caller":"traceutil/trace.go:171","msg":"trace[1926478756] transaction","detail":"{read_only:false; response_revision:1857; number_of_response:1; }","duration":"400.696233ms","start":"2026-04-04T10:24:58.10537Z","end":"2026-04-04T10:24:58.506066Z","steps":["trace[1926478756] 'process raft request' (duration: 400.491539ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:58.506109Z","caller":"traceutil/trace.go:171","msg":"trace[1735344930] transaction","detail":"{read_only:false; response_revision:1856; number_of_response:1; }","duration":"400.931888ms","start":"2026-04-04T10:24:58.105154Z","end":"2026-04-04T10:24:58.506086Z","steps":["trace[1735344930] 'process raft request' (duration: 400.506899ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:58.506103Z","caller":"traceutil/trace.go:171","msg":"trace[1685637494] linearizableReadLoop","detail":"{readStateIndex:1930; appliedIndex:1927; }","duration":"310.891491ms","start":"2026-04-04T10:24:58.195178Z","end":"2026-04-04T10:24:58.50607Z","steps":["trace[1685637494] 'read index received' (duration: 237.25977ms)","trace[1685637494] 'applied index is now lower than readState.Index' (duration: 73.630391ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:24:58.506242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:24:58.105347Z","time spent":"400.819675ms","remote":"127.0.0.1:43888","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:24:58.506241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:24:58.105141Z","time spent":"401.033711ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:24:58.506385Z","caller":"traceutil/trace.go:171","msg":"trace[113685628] transaction","detail":"{read_only:false; response_revision:1858; number_of_response:1; }","duration":"289.553726ms","start":"2026-04-04T10:24:58.216782Z","end":"2026-04-04T10:24:58.506336Z","steps":["trace[113685628] 'process raft request' (duration: 289.227169ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:58.50654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.284009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:24:58.5067Z","caller":"traceutil/trace.go:171","msg":"trace[386663178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1858; }","duration":"311.545544ms","start":"2026-04-04T10:24:58.19512Z","end":"2026-04-04T10:24:58.506666Z","steps":["trace[386663178] 'agreement among raft nodes before linearized reading' (duration: 311.093315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:58.506775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:24:58.105046Z","time spent":"400.899077ms","remote":"127.0.0.1:43998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-04T10:24:58.506819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:24:58.195102Z","time spent":"311.697588ms","remote":"127.0.0.1:43728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-04T10:24:58.511318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.610907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c0f8bb18-2366-4adb-a6b0-56ddd3f3a8b5\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-04T10:24:58.511368Z","caller":"traceutil/trace.go:171","msg":"trace[1132442086] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c0f8bb18-2366-4adb-a6b0-56ddd3f3a8b5; range_end:; response_count:1; response_revision:1859; }","duration":"162.700479ms","start":"2026-04-04T10:24:58.34865Z","end":"2026-04-04T10:24:58.511351Z","steps":["trace[1132442086] 'agreement among raft nodes before linearized reading' (duration: 162.541406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:58.511577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.6686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:24:58.511658Z","caller":"traceutil/trace.go:171","msg":"trace[556188116] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1859; }","duration":"219.802993ms","start":"2026-04-04T10:24:58.291819Z","end":"2026-04-04T10:24:58.511622Z","steps":["trace[556188116] 'agreement among raft nodes before linearized reading' (duration: 219.67531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:24:58.511793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.147746ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:24:58.511958Z","caller":"traceutil/trace.go:171","msg":"trace[864519072] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1859; }","duration":"186.535964ms","start":"2026-04-04T10:24:58.325405Z","end":"2026-04-04T10:24:58.511941Z","steps":["trace[864519072] 'agreement among raft nodes before linearized reading' (duration: 185.917502ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:24:58.883088Z","caller":"traceutil/trace.go:171","msg":"trace[1927624917] linearizableReadLoop","detail":"{readStateIndex:1935; appliedIndex:1934; }","duration":"154.399261ms","start":"2026-04-04T10:24:58.728657Z","end":"2026-04-04T10:24:58.883057Z","steps":["trace[1927624917] 'read index received' (duration: 130.328039ms)","trace[1927624917] 'applied index is now lower than readState.Index' (duration: 24.070111ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:24:58.883226Z","caller":"traceutil/trace.go:171","msg":"trace[1594629577] transaction","detail":"{read_only:false; response_revision:1862; number_of_response:1; }","duration":"173.08653ms","start":"2026-04-04T10:24:58.710108Z","end":"2026-04-04T10:24:58.883194Z","steps":["trace[1594629577] 'process raft request' (duration: 148.953967ms)","trace[1594629577] 'compare' (duration: 23.849748ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:24:58.88343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.704667ms","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-04-04T10:24:58.883485Z","caller":"traceutil/trace.go:171","msg":"trace[1787981017] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1862; }","duration":"154.84904ms","start":"2026-04-04T10:24:58.728624Z","end":"2026-04-04T10:24:58.883474Z","steps":["trace[1787981017] 'agreement among raft nodes before linearized reading' (duration: 154.547794ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:10.79384Z","caller":"traceutil/trace.go:171","msg":"trace[417034962] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"240.625618ms","start":"2026-04-04T10:25:10.553182Z","end":"2026-04-04T10:25:10.793808Z","steps":["trace[417034962] 'process raft request' (duration: 240.428414ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:10.81885Z","caller":"traceutil/trace.go:171","msg":"trace[1422017219] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"264.371302ms","start":"2026-04-04T10:25:10.554451Z","end":"2026-04-04T10:25:10.818822Z","steps":["trace[1422017219] 'process raft request' (duration: 264.196619ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.063088Z","caller":"traceutil/trace.go:171","msg":"trace[1812129574] transaction","detail":"{read_only:false; response_revision:2181; number_of_response:1; }","duration":"127.307309ms","start":"2026-04-04T10:25:48.935749Z","end":"2026-04-04T10:25:49.063056Z","steps":["trace[1812129574] 'process raft request' (duration: 127.133706ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.207563Z","caller":"traceutil/trace.go:171","msg":"trace[124960517] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"255.972797ms","start":"2026-04-04T10:25:48.951561Z","end":"2026-04-04T10:25:49.207534Z","steps":["trace[124960517] 'process raft request' (duration: 241.166957ms)","trace[124960517] 'compare' (duration: 14.598886ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:49.207982Z","caller":"traceutil/trace.go:171","msg":"trace[992298891] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"236.839059ms","start":"2026-04-04T10:25:48.97106Z","end":"2026-04-04T10:25:49.207899Z","steps":["trace[992298891] 'process raft request' (duration: 236.413101ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:25:49.208219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.701667ms","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-04-04T10:25:49.208304Z","caller":"traceutil/trace.go:171","msg":"trace[1727746231] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2183; }","duration":"201.82657ms","start":"2026-04-04T10:25:49.006452Z","end":"2026-04-04T10:25:49.208279Z","steps":["trace[1727746231] 'agreement among raft nodes before linearized reading' (duration: 201.579235ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.208463Z","caller":"traceutil/trace.go:171","msg":"trace[1085845476] linearizableReadLoop","detail":"{readStateIndex:2266; appliedIndex:2263; }","duration":"201.298359ms","start":"2026-04-04T10:25:49.006502Z","end":"2026-04-04T10:25:49.2078Z","steps":["trace[1085845476] 'read index received' (duration: 56.374143ms)","trace[1085845476] 'applied index is now lower than readState.Index' (duration: 144.922176ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:25:49.503597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.242553ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:25:49.503755Z","caller":"traceutil/trace.go:171","msg":"trace[769983010] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"292.339923ms","start":"2026-04-04T10:25:49.211388Z","end":"2026-04-04T10:25:49.503728Z","steps":["trace[769983010] 'process raft request' (duration: 104.63463ms)","trace[769983010] 'compare' (duration: 187.051069ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:25:49.504557Z","caller":"traceutil/trace.go:171","msg":"trace[1994817624] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"291.949505ms","start":"2026-04-04T10:25:49.212591Z","end":"2026-04-04T10:25:49.504541Z","steps":["trace[1994817624] 'process raft request' (duration: 291.73454ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.504667Z","caller":"traceutil/trace.go:171","msg":"trace[1492800192] transaction","detail":"{read_only:false; response_revision:2186; number_of_response:1; }","duration":"291.958725ms","start":"2026-04-04T10:25:49.212675Z","end":"2026-04-04T10:25:49.504634Z","steps":["trace[1492800192] 'process raft request' (duration: 291.781412ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.504768Z","caller":"traceutil/trace.go:171","msg":"trace[127963153] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"290.457905ms","start":"2026-04-04T10:25:49.214297Z","end":"2026-04-04T10:25:49.504755Z","steps":["trace[127963153] 'process raft request' (duration: 290.20975ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:25:49.504792Z","caller":"traceutil/trace.go:171","msg":"trace[1849236182] linearizableReadLoop","detail":"{readStateIndex:2270; appliedIndex:2267; }","duration":"155.864349ms","start":"2026-04-04T10:25:49.348881Z","end":"2026-04-04T10:25:49.504746Z","steps":["trace[1849236182] 'read index received' (duration: 155.354539ms)","trace[1849236182] 'applied index is now lower than readState.Index' (duration: 508.63µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:25:49.505029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.132424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-04-04T10:25:49.505074Z","caller":"traceutil/trace.go:171","msg":"trace[219900100] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2187; }","duration":"156.248106ms","start":"2026-04-04T10:25:49.348813Z","end":"2026-04-04T10:25:49.505061Z","steps":["trace[219900100] 'agreement among raft nodes before linearized reading' (duration: 156.029112ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:26:38.185771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.599084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-04-04T10:26:38.185886Z","caller":"traceutil/trace.go:171","msg":"trace[480027878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2623; }","duration":"173.793548ms","start":"2026-04-04T10:26:38.012068Z","end":"2026-04-04T10:26:38.185862Z","steps":["trace[480027878] 'range keys from in-memory index tree' (duration: 173.363469ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:27:12.106329Z","caller":"traceutil/trace.go:171","msg":"trace[219177099] transaction","detail":"{read_only:false; response_revision:2825; number_of_response:1; }","duration":"149.915549ms","start":"2026-04-04T10:27:11.956369Z","end":"2026-04-04T10:27:12.106284Z","steps":["trace[219177099] 'process raft request' (duration: 149.617803ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:27:12.115884Z","caller":"traceutil/trace.go:171","msg":"trace[1016053173] transaction","detail":"{read_only:false; response_revision:2826; number_of_response:1; }","duration":"158.314549ms","start":"2026-04-04T10:27:11.957525Z","end":"2026-04-04T10:27:12.115839Z","steps":["trace[1016053173] 'process raft request' (duration: 158.086013ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:27:12.488313Z","caller":"traceutil/trace.go:171","msg":"trace[129872319] linearizableReadLoop","detail":"{readStateIndex:2937; appliedIndex:2936; }","duration":"140.211155ms","start":"2026-04-04T10:27:12.348072Z","end":"2026-04-04T10:27:12.488283Z","steps":["trace[129872319] 'read index received' (duration: 52.305151ms)","trace[129872319] 'applied index is now lower than readState.Index' (duration: 87.904894ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:27:12.488455Z","caller":"traceutil/trace.go:171","msg":"trace[571793532] transaction","detail":"{read_only:false; response_revision:2828; number_of_response:1; }","duration":"169.953872ms","start":"2026-04-04T10:27:12.318445Z","end":"2026-04-04T10:27:12.488399Z","steps":["trace[571793532] 'process raft request' (duration: 82.084758ms)","trace[571793532] 'compare' (duration: 87.631928ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:27:12.488572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.47988ms","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-04-04T10:27:12.488614Z","caller":"traceutil/trace.go:171","msg":"trace[292631468] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2828; }","duration":"140.626103ms","start":"2026-04-04T10:27:12.347977Z","end":"2026-04-04T10:27:12.488603Z","steps":["trace[292631468] 'agreement among raft nodes before linearized reading' (duration: 140.417689ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:13.34812Z","caller":"traceutil/trace.go:171","msg":"trace[2119851073] linearizableReadLoop","detail":"{readStateIndex:3797; appliedIndex:3796; }","duration":"153.105625ms","start":"2026-04-04T10:29:13.194993Z","end":"2026-04-04T10:29:13.348098Z","steps":["trace[2119851073] 'read index received' (duration: 152.894891ms)","trace[2119851073] 'applied index is now lower than readState.Index' (duration: 209.224µs)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:29:13.348274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.265149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:29:13.348247Z","caller":"traceutil/trace.go:171","msg":"trace[934880034] transaction","detail":"{read_only:false; response_revision:3657; number_of_response:1; }","duration":"249.445226ms","start":"2026-04-04T10:29:13.098771Z","end":"2026-04-04T10:29:13.348217Z","steps":["trace[934880034] 'process raft request' (duration: 249.1336ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:13.34831Z","caller":"traceutil/trace.go:171","msg":"trace[255473693] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3657; }","duration":"153.362381ms","start":"2026-04-04T10:29:13.194937Z","end":"2026-04-04T10:29:13.3483Z","steps":["trace[255473693] 'agreement among raft nodes before linearized reading' (duration: 153.277829ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:15.085282Z","caller":"traceutil/trace.go:171","msg":"trace[192699632] transaction","detail":"{read_only:false; response_revision:3666; number_of_response:1; }","duration":"172.867644ms","start":"2026-04-04T10:29:14.912397Z","end":"2026-04-04T10:29:15.085264Z","steps":["trace[192699632] 'process raft request' (duration: 172.753681ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:15.223159Z","caller":"traceutil/trace.go:171","msg":"trace[2131581977] transaction","detail":"{read_only:false; response_revision:3668; number_of_response:1; }","duration":"111.135789ms","start":"2026-04-04T10:29:15.112002Z","end":"2026-04-04T10:29:15.223138Z","steps":["trace[2131581977] 'process raft request' (duration: 111.023327ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:15.223179Z","caller":"traceutil/trace.go:171","msg":"trace[489974213] transaction","detail":"{read_only:false; response_revision:3667; number_of_response:1; }","duration":"194.679443ms","start":"2026-04-04T10:29:15.028448Z","end":"2026-04-04T10:29:15.223127Z","steps":["trace[489974213] 'process raft request' (duration: 110.087919ms)","trace[489974213] 'compare' (duration: 84.307008ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:29:38.28515Z","caller":"traceutil/trace.go:171","msg":"trace[1885125015] linearizableReadLoop","detail":"{readStateIndex:3944; appliedIndex:3943; }","duration":"179.585578ms","start":"2026-04-04T10:29:38.105532Z","end":"2026-04-04T10:29:38.285117Z","steps":["trace[1885125015] 'read index received' (duration: 179.436445ms)","trace[1885125015] 'applied index is now lower than readState.Index' (duration: 147.833µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:29:38.285221Z","caller":"traceutil/trace.go:171","msg":"trace[1670304609] transaction","detail":"{read_only:false; response_revision:3799; number_of_response:1; }","duration":"227.027574ms","start":"2026-04-04T10:29:38.058151Z","end":"2026-04-04T10:29:38.285178Z","steps":["trace[1670304609] 'process raft request' (duration: 226.753439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:29:38.285424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.849133ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-04T10:29:38.285527Z","caller":"traceutil/trace.go:171","msg":"trace[1145042675] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3799; }","duration":"179.984887ms","start":"2026-04-04T10:29:38.105524Z","end":"2026-04-04T10:29:38.285508Z","steps":["trace[1145042675] 'agreement among raft nodes before linearized reading' (duration: 179.777762ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:29:38.307414Z","caller":"traceutil/trace.go:171","msg":"trace[358813081] transaction","detail":"{read_only:false; response_revision:3800; number_of_response:1; }","duration":"180.923235ms","start":"2026-04-04T10:29:38.126462Z","end":"2026-04-04T10:29:38.307386Z","steps":["trace[358813081] 'process raft request' (duration: 180.519617ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:29:38.307451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.72001ms","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-04-04T10:29:38.307531Z","caller":"traceutil/trace.go:171","msg":"trace[1270907924] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:3800; }","duration":"180.816692ms","start":"2026-04-04T10:29:38.126696Z","end":"2026-04-04T10:29:38.307512Z","steps":["trace[1270907924] 'agreement among raft nodes before linearized reading' (duration: 180.645529ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:57.974707Z","caller":"traceutil/trace.go:171","msg":"trace[290377886] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"117.028898ms","start":"2026-04-04T10:30:57.85765Z","end":"2026-04-04T10:30:57.974679Z","steps":["trace[290377886] 'process raft request' (duration: 73.926411ms)","trace[290377886] 'compare' (duration: 42.956384ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:30:57.982969Z","caller":"traceutil/trace.go:171","msg":"trace[1089619052] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"124.66302ms","start":"2026-04-04T10:30:57.85828Z","end":"2026-04-04T10:30:57.982943Z","steps":["trace[1089619052] 'process raft request' (duration: 124.331883ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:57.983015Z","caller":"traceutil/trace.go:171","msg":"trace[582286830] transaction","detail":"{read_only:false; response_revision:4743; number_of_response:1; }","duration":"120.1477ms","start":"2026-04-04T10:30:57.862835Z","end":"2026-04-04T10:30:57.982982Z","steps":["trace[582286830] 'process raft request' (duration: 119.966297ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:57.983062Z","caller":"traceutil/trace.go:171","msg":"trace[1982585294] transaction","detail":"{read_only:false; response_revision:4744; number_of_response:1; }","duration":"116.513278ms","start":"2026-04-04T10:30:57.866513Z","end":"2026-04-04T10:30:57.983027Z","steps":["trace[1982585294] 'process raft request' (duration: 116.362145ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:30:57.983294Z","caller":"traceutil/trace.go:171","msg":"trace[166555006] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"112.511498ms","start":"2026-04-04T10:30:57.870754Z","end":"2026-04-04T10:30:57.983265Z","steps":["trace[166555006] 'process raft request' (duration: 112.194602ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.489078Z","caller":"traceutil/trace.go:171","msg":"trace[34465416] linearizableReadLoop","detail":"{readStateIndex:5395; appliedIndex:5394; }","duration":"119.125459ms","start":"2026-04-04T10:31:17.369924Z","end":"2026-04-04T10:31:17.48905Z","steps":["trace[34465416] 'read index received' (duration: 118.731621ms)","trace[34465416] 'applied index is now lower than readState.Index' (duration: 329.737µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:31:17.489094Z","caller":"traceutil/trace.go:171","msg":"trace[1792527682] transaction","detail":"{read_only:false; response_revision:5197; number_of_response:1; }","duration":"247.662876ms","start":"2026-04-04T10:31:17.241385Z","end":"2026-04-04T10:31:17.489048Z","steps":["trace[1792527682] 'process raft request' (duration: 247.3725ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:17.489409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.464275ms","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-04-04T10:31:17.489456Z","caller":"traceutil/trace.go:171","msg":"trace[1737414181] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5197; }","duration":"119.551207ms","start":"2026-04-04T10:31:17.369893Z","end":"2026-04-04T10:31:17.489444Z","steps":["trace[1737414181] 'agreement among raft nodes before linearized reading' (duration: 119.328252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:17.489533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.099648ms","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":"warn","ts":"2026-04-04T10:31:17.489605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.453245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:31:17.489727Z","caller":"traceutil/trace.go:171","msg":"trace[1760274655] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5197; }","duration":"119.660309ms","start":"2026-04-04T10:31:17.370039Z","end":"2026-04-04T10:31:17.4897Z","steps":["trace[1760274655] 'agreement among raft nodes before linearized reading' (duration: 119.22108ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.489651Z","caller":"traceutil/trace.go:171","msg":"trace[1045686173] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5197; }","duration":"118.279982ms","start":"2026-04-04T10:31:17.37135Z","end":"2026-04-04T10:31:17.48963Z","steps":["trace[1045686173] 'agreement among raft nodes before linearized reading' (duration: 117.944946ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.622556Z","caller":"traceutil/trace.go:171","msg":"trace[1818133808] transaction","detail":"{read_only:false; response_revision:5198; number_of_response:1; }","duration":"130.869003ms","start":"2026-04-04T10:31:17.491645Z","end":"2026-04-04T10:31:17.622514Z","steps":["trace[1818133808] 'process raft request' (duration: 93.222464ms)","trace[1818133808] 'compare' (duration: 37.241381ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:31:17.622945Z","caller":"traceutil/trace.go:171","msg":"trace[454799234] transaction","detail":"{read_only:false; response_revision:5202; number_of_response:1; }","duration":"115.396615ms","start":"2026-04-04T10:31:17.507525Z","end":"2026-04-04T10:31:17.622921Z","steps":["trace[454799234] 'process raft request' (duration: 115.312544ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.622968Z","caller":"traceutil/trace.go:171","msg":"trace[1128411917] transaction","detail":"{read_only:false; response_revision:5199; number_of_response:1; }","duration":"129.312123ms","start":"2026-04-04T10:31:17.493627Z","end":"2026-04-04T10:31:17.622939Z","steps":["trace[1128411917] 'process raft request' (duration: 128.778422ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.623075Z","caller":"traceutil/trace.go:171","msg":"trace[1093161925] transaction","detail":"{read_only:false; response_revision:5200; number_of_response:1; }","duration":"128.404404ms","start":"2026-04-04T10:31:17.494623Z","end":"2026-04-04T10:31:17.623027Z","steps":["trace[1093161925] 'process raft request' (duration: 127.965386ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:17.623955Z","caller":"traceutil/trace.go:171","msg":"trace[1101961] transaction","detail":"{read_only:false; response_revision:5201; number_of_response:1; }","duration":"128.65384ms","start":"2026-04-04T10:31:17.495262Z","end":"2026-04-04T10:31:17.623916Z","steps":["trace[1101961] 'process raft request' (duration: 127.465366ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:43.742224Z","caller":"traceutil/trace.go:171","msg":"trace[135768686] linearizableReadLoop","detail":"{readStateIndex:5783; appliedIndex:5782; }","duration":"411.014155ms","start":"2026-04-04T10:31:43.331186Z","end":"2026-04-04T10:31:43.7422Z","steps":["trace[135768686] 'read index received' (duration: 410.75067ms)","trace[135768686] 'applied index is now lower than readState.Index' (duration: 262.605µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:31:43.742351Z","caller":"traceutil/trace.go:171","msg":"trace[2039359229] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"418.966084ms","start":"2026-04-04T10:31:43.323352Z","end":"2026-04-04T10:31:43.742318Z","steps":["trace[2039359229] 'process raft request' (duration: 418.669998ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:43.74249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.300171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"warn","ts":"2026-04-04T10:31:43.742491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.443666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-04T10:31:43.742527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:31:43.323329Z","time spent":"419.106166ms","remote":"127.0.0.1:43914","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10667,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-04T10:31:43.742553Z","caller":"traceutil/trace.go:171","msg":"trace[1891081602] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5578; }","duration":"411.416693ms","start":"2026-04-04T10:31:43.331118Z","end":"2026-04-04T10:31:43.742535Z","steps":["trace[1891081602] 'agreement among raft nodes before linearized reading' (duration: 411.22961ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:43.74256Z","caller":"traceutil/trace.go:171","msg":"trace[24851665] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5578; }","duration":"301.558769ms","start":"2026-04-04T10:31:43.440986Z","end":"2026-04-04T10:31:43.742544Z","steps":["trace[24851665] 'agreement among raft nodes before linearized reading' (duration: 301.403175ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:31:43.742597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:31:43.331097Z","time spent":"411.490334ms","remote":"127.0.0.1:43992","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-04-04T10:31:43.742599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-04T10:31:43.44097Z","time spent":"301.618749ms","remote":"127.0.0.1:47834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":38,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"info","ts":"2026-04-04T10:31:43.850995Z","caller":"traceutil/trace.go:171","msg":"trace[1173574836] transaction","detail":"{read_only:false; response_revision:5580; number_of_response:1; }","duration":"101.655751ms","start":"2026-04-04T10:31:43.749306Z","end":"2026-04-04T10:31:43.850962Z","steps":["trace[1173574836] 'process raft request' (duration: 61.780089ms)","trace[1173574836] 'compare' (duration: 39.632747ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:31:43.851035Z","caller":"traceutil/trace.go:171","msg":"trace[1587518274] transaction","detail":"{read_only:false; response_revision:5582; number_of_response:1; }","duration":"101.149792ms","start":"2026-04-04T10:31:43.749857Z","end":"2026-04-04T10:31:43.851006Z","steps":["trace[1587518274] 'process raft request' (duration: 101.03567ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:43.851464Z","caller":"traceutil/trace.go:171","msg":"trace[1742897866] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"100.649331ms","start":"2026-04-04T10:31:43.750787Z","end":"2026-04-04T10:31:43.851436Z","steps":["trace[1742897866] 'process raft request' (duration: 100.173681ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:31:43.851541Z","caller":"traceutil/trace.go:171","msg":"trace[1985368163] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"101.446938ms","start":"2026-04-04T10:31:43.750081Z","end":"2026-04-04T10:31:43.851528Z","steps":["trace[1985368163] 'process raft request' (duration: 100.770784ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:32:51.167062Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3040} {"level":"info","ts":"2026-04-04T10:32:51.25763Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3040,"took":"88.818609ms","hash":3984196546,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":31703040,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-04T10:32:51.25775Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3984196546,"revision":3040,"compact-revision":-1} {"level":"warn","ts":"2026-04-04T10:37:46.413305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.241693ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:37:46.413781Z","caller":"traceutil/trace.go:171","msg":"trace[1226113279] transaction","detail":"{read_only:false; response_revision:8334; number_of_response:1; }","duration":"207.670868ms","start":"2026-04-04T10:37:46.206085Z","end":"2026-04-04T10:37:46.413756Z","steps":["trace[1226113279] 'process raft request' (duration: 207.558106ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:37:46.413798Z","caller":"traceutil/trace.go:171","msg":"trace[1862396661] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"217.178257ms","start":"2026-04-04T10:37:46.196534Z","end":"2026-04-04T10:37:46.413712Z","steps":["trace[1862396661] 'read index received' (duration: 46.434965ms)","trace[1862396661] 'applied index is now lower than readState.Index' (duration: 170.737952ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:37:46.413838Z","caller":"traceutil/trace.go:171","msg":"trace[923602875] transaction","detail":"{read_only:false; response_revision:8333; number_of_response:1; }","duration":"235.539963ms","start":"2026-04-04T10:37:46.178224Z","end":"2026-04-04T10:37:46.413764Z","steps":["trace[923602875] 'process raft request' (duration: 64.724299ms)","trace[923602875] 'compare' (duration: 170.08896ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:37:46.413906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.35478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:37:46.414008Z","caller":"traceutil/trace.go:171","msg":"trace[1195662478] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8334; }","duration":"217.502613ms","start":"2026-04-04T10:37:46.196487Z","end":"2026-04-04T10:37:46.41399Z","steps":["trace[1195662478] 'agreement among raft nodes before linearized reading' (duration: 217.3444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:37:46.41411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.457383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-04T10:37:46.414157Z","caller":"traceutil/trace.go:171","msg":"trace[594623446] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8334; }","duration":"207.507684ms","start":"2026-04-04T10:37:46.206638Z","end":"2026-04-04T10:37:46.414146Z","steps":["trace[594623446] 'agreement among raft nodes before linearized reading' (duration: 207.324161ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:37:51.17468Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6171} {"level":"info","ts":"2026-04-04T10:37:51.277852Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6171,"took":"100.096554ms","hash":2115273761,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":23785472,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-04-04T10:37:51.278101Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2115273761,"revision":6171,"compact-revision":3040} {"level":"info","ts":"2026-04-04T10:37:58.703165Z","caller":"traceutil/trace.go:171","msg":"trace[648863876] transaction","detail":"{read_only:false; response_revision:8395; number_of_response:1; }","duration":"238.88642ms","start":"2026-04-04T10:37:58.464239Z","end":"2026-04-04T10:37:58.703126Z","steps":["trace[648863876] 'process raft request' (duration: 238.642886ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:37:58.711994Z","caller":"traceutil/trace.go:171","msg":"trace[1871809198] linearizableReadLoop","detail":"{readStateIndex:8692; appliedIndex:8690; }","duration":"241.729376ms","start":"2026-04-04T10:37:58.470239Z","end":"2026-04-04T10:37:58.711969Z","steps":["trace[1871809198] 'read index received' (duration: 232.587804ms)","trace[1871809198] 'applied index is now lower than readState.Index' (duration: 9.140252ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:37:58.71215Z","caller":"traceutil/trace.go:171","msg":"trace[1487091723] transaction","detail":"{read_only:false; response_revision:8396; number_of_response:1; }","duration":"247.054213ms","start":"2026-04-04T10:37:58.465056Z","end":"2026-04-04T10:37:58.71211Z","steps":["trace[1487091723] 'process raft request' (duration: 246.778298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:37:58.712251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.992401ms","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-04-04T10:37:58.712324Z","caller":"traceutil/trace.go:171","msg":"trace[2021346769] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8396; }","duration":"242.095354ms","start":"2026-04-04T10:37:58.470203Z","end":"2026-04-04T10:37:58.712298Z","steps":["trace[2021346769] 'agreement among raft nodes before linearized reading' (duration: 241.91478ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:37:59.934654Z","caller":"traceutil/trace.go:171","msg":"trace[1536935833] transaction","detail":"{read_only:false; response_revision:8401; number_of_response:1; }","duration":"123.245576ms","start":"2026-04-04T10:37:59.811369Z","end":"2026-04-04T10:37:59.934614Z","steps":["trace[1536935833] 'process raft request' (duration: 123.048093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:38:31.097284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.847126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-04T10:38:31.097647Z","caller":"traceutil/trace.go:171","msg":"trace[289248748] linearizableReadLoop","detail":"{readStateIndex:8929; appliedIndex:8927; }","duration":"191.768152ms","start":"2026-04-04T10:38:30.905853Z","end":"2026-04-04T10:38:31.097621Z","steps":["trace[289248748] 'read index received' (duration: 41.430956ms)","trace[289248748] 'applied index is now lower than readState.Index' (duration: 150.336066ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:38:31.097595Z","caller":"traceutil/trace.go:171","msg":"trace[1546410035] transaction","detail":"{read_only:false; response_revision:8623; number_of_response:1; }","duration":"277.099952ms","start":"2026-04-04T10:38:30.82045Z","end":"2026-04-04T10:38:31.09755Z","steps":["trace[1546410035] 'process raft request' (duration: 126.832247ms)","trace[1546410035] 'compare' (duration: 149.448399ms)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:38:31.097716Z","caller":"traceutil/trace.go:171","msg":"trace[2131173061] transaction","detail":"{read_only:false; response_revision:8624; number_of_response:1; }","duration":"273.417379ms","start":"2026-04-04T10:38:30.824267Z","end":"2026-04-04T10:38:31.097685Z","steps":["trace[2131173061] 'process raft request' (duration: 273.216405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:38:31.097935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.056057ms","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-04-04T10:38:31.09825Z","caller":"traceutil/trace.go:171","msg":"trace[758623825] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8624; }","duration":"192.440695ms","start":"2026-04-04T10:38:30.90579Z","end":"2026-04-04T10:38:31.098231Z","steps":["trace[758623825] 'agreement among raft nodes before linearized reading' (duration: 191.955216ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:38:31.268303Z","caller":"traceutil/trace.go:171","msg":"trace[2134532190] linearizableReadLoop","detail":"{readStateIndex:8930; appliedIndex:8929; }","duration":"164.934267ms","start":"2026-04-04T10:38:31.103339Z","end":"2026-04-04T10:38:31.268274Z","steps":["trace[2134532190] 'read index received' (duration: 164.61077ms)","trace[2134532190] 'applied index is now lower than readState.Index' (duration: 321.807µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:38:31.268592Z","caller":"traceutil/trace.go:171","msg":"trace[2054165236] transaction","detail":"{read_only:false; response_revision:8625; number_of_response:1; }","duration":"166.204982ms","start":"2026-04-04T10:38:31.102378Z","end":"2026-04-04T10:38:31.268583Z","steps":["trace[2054165236] 'process raft request' (duration: 165.683852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:38:31.26917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.814864ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:26 size:228865"} {"level":"info","ts":"2026-04-04T10:38:31.269208Z","caller":"traceutil/trace.go:171","msg":"trace[1459712874] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:26; response_revision:8625; }","duration":"165.877535ms","start":"2026-04-04T10:38:31.103321Z","end":"2026-04-04T10:38:31.269199Z","steps":["trace[1459712874] 'agreement among raft nodes before linearized reading' (duration: 165.484797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:38:31.269257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.167963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:38:31.26939Z","caller":"traceutil/trace.go:171","msg":"trace[1018316068] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:8625; }","duration":"140.367967ms","start":"2026-04-04T10:38:31.128995Z","end":"2026-04-04T10:38:31.269363Z","steps":["trace[1018316068] 'agreement among raft nodes before linearized reading' (duration: 140.131222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:38:40.048909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.682717ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-04T10:38:40.049032Z","caller":"traceutil/trace.go:171","msg":"trace[708861914] linearizableReadLoop","detail":"{readStateIndex:9023; appliedIndex:9022; }","duration":"168.081919ms","start":"2026-04-04T10:38:39.880928Z","end":"2026-04-04T10:38:40.04901Z","steps":["trace[708861914] 'read index received' (duration: 83.742µs)","trace[708861914] 'applied index is now lower than readState.Index' (duration: 167.996247ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:38:40.04914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.204581ms","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-04-04T10:38:40.049177Z","caller":"traceutil/trace.go:171","msg":"trace[1424896487] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:8714; }","duration":"168.306523ms","start":"2026-04-04T10:38:39.88086Z","end":"2026-04-04T10:38:40.049167Z","steps":["trace[1424896487] 'agreement among raft nodes before linearized reading' (duration: 168.210001ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:39:09.559032Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000247f.wal"} {"level":"info","ts":"2026-04-04T10:40:00.839116Z","caller":"traceutil/trace.go:171","msg":"trace[1095473067] linearizableReadLoop","detail":"{readStateIndex:9815; appliedIndex:9814; }","duration":"184.785983ms","start":"2026-04-04T10:40:00.654305Z","end":"2026-04-04T10:40:00.839091Z","steps":["trace[1095473067] 'read index received' (duration: 184.738563ms)","trace[1095473067] 'applied index is now lower than readState.Index' (duration: 45.99µs)"],"step_count":2} {"level":"info","ts":"2026-04-04T10:40:00.839263Z","caller":"traceutil/trace.go:171","msg":"trace[1181829100] transaction","detail":"{read_only:false; response_revision:9485; number_of_response:1; }","duration":"217.747181ms","start":"2026-04-04T10:40:00.621478Z","end":"2026-04-04T10:40:00.839225Z","steps":["trace[1181829100] 'process raft request' (duration: 217.452015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:40:00.839596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.07818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-04T10:40:00.840017Z","caller":"traceutil/trace.go:171","msg":"trace[909952811] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9485; }","duration":"185.714732ms","start":"2026-04-04T10:40:00.654286Z","end":"2026-04-04T10:40:00.840001Z","steps":["trace[909952811] 'agreement among raft nodes before linearized reading' (duration: 184.969878ms)"],"step_count":1} {"level":"warn","ts":"2026-04-04T10:40:00.83965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.600131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-04T10:40:00.840204Z","caller":"traceutil/trace.go:171","msg":"trace[129210135] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:9485; }","duration":"173.210454ms","start":"2026-04-04T10:40:00.666951Z","end":"2026-04-04T10:40:00.840161Z","steps":["trace[129210135] 'agreement among raft nodes before linearized reading' (duration: 172.576671ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:00.998213Z","caller":"traceutil/trace.go:171","msg":"trace[164457810] transaction","detail":"{read_only:false; response_revision:9487; number_of_response:1; }","duration":"152.46658ms","start":"2026-04-04T10:40:00.845712Z","end":"2026-04-04T10:40:00.998178Z","steps":["trace[164457810] 'process raft request' (duration: 152.225334ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:00.998563Z","caller":"traceutil/trace.go:171","msg":"trace[836872697] transaction","detail":"{read_only:false; response_revision:9488; number_of_response:1; }","duration":"152.442049ms","start":"2026-04-04T10:40:00.846106Z","end":"2026-04-04T10:40:00.998548Z","steps":["trace[836872697] 'process raft request' (duration: 152.025491ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:00.998514Z","caller":"traceutil/trace.go:171","msg":"trace[1531859335] linearizableReadLoop","detail":"{readStateIndex:9818; appliedIndex:9815; }","duration":"150.368648ms","start":"2026-04-04T10:40:00.848107Z","end":"2026-04-04T10:40:00.998476Z","steps":["trace[1531859335] 'read index received' (duration: 16.869856ms)","trace[1531859335] 'applied index is now lower than readState.Index' (duration: 133.496842ms)"],"step_count":2} {"level":"warn","ts":"2026-04-04T10:40:00.998788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.656043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-04T10:40:00.998854Z","caller":"traceutil/trace.go:171","msg":"trace[885333058] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:9488; }","duration":"150.783746ms","start":"2026-04-04T10:40:00.848058Z","end":"2026-04-04T10:40:00.998842Z","steps":["trace[885333058] 'agreement among raft nodes before linearized reading' (duration: 150.535811ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:01.008818Z","caller":"traceutil/trace.go:171","msg":"trace[1016355564] transaction","detail":"{read_only:false; response_revision:9489; number_of_response:1; }","duration":"102.316199ms","start":"2026-04-04T10:40:00.906464Z","end":"2026-04-04T10:40:01.00878Z","steps":["trace[1016355564] 'process raft request' (duration: 102.137956ms)"],"step_count":1} {"level":"info","ts":"2026-04-04T10:40:23.556145Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"8a0c9a032e61a05a","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-04T10:40:23.562816Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-04T10:40:23.562924Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-04T10:42:51.182015Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8358} {"level":"info","ts":"2026-04-04T10:42:51.264661Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8358,"took":"80.151857ms","hash":1567181796,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":24965120,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-04-04T10:42:51.264746Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1567181796,"revision":8358,"compact-revision":6171} {"level":"info","ts":"2026-04-04T10:47:51.189236Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10581} {"level":"info","ts":"2026-04-04T10:47:51.270237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10581,"took":"78.276704ms","hash":863859756,"current-db-size-bytes":58179584,"current-db-size":"58 MB","current-db-size-in-use-bytes":21536768,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-04T10:47:51.270312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":863859756,"revision":10581,"compact-revision":8358}