{"level":"warn","ts":"2026-03-24T14:47:26.434792Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:47:26.434897Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.44: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.44:2380","--initial-cluster=instance=https://199.19.213.44:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.44:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.44: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-03-24T14:47:26.434972Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-03-24T14:47:26.434982Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-03-24T14:47:26.435002Z","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-03-24T14:47:26.435644Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"]} {"level":"info","ts":"2026-03-24T14:47:26.435754Z","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.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.44: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-03-24T14:47:26.441539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.582659ms"} {"level":"info","ts":"2026-03-24T14:47:26.450744Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"4f2979fc0f638003","cluster-id":"ce06e7bc3b23612b"} {"level":"info","ts":"2026-03-24T14:47:26.450843Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=()"} {"level":"info","ts":"2026-03-24T14:47:26.450873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 0"} {"level":"info","ts":"2026-03-24T14:47:26.450887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 4f2979fc0f638003 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-03-24T14:47:26.450899Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became follower at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.450951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"warn","ts":"2026-03-24T14:47:26.460749Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-03-24T14:47:26.462854Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-03-24T14:47:26.464356Z","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-03-24T14:47:26.468271Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"4f2979fc0f638003","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-03-24T14:47:26.470998Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"4f2979fc0f638003","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-03-24T14:47:26.471218Z","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-03-24T14:47:26.471266Z","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-03-24T14:47:26.471284Z","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-03-24T14:47:26.473038Z","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-03-24T14:47:26.473173Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-03-24T14:47:26.473198Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.44:2380"} {"level":"info","ts":"2026-03-24T14:47:26.473786Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 switched to configuration voters=(5704224526538342403)"} {"level":"info","ts":"2026-03-24T14:47:26.47389Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","added-peer-id":"4f2979fc0f638003","added-peer-peer-urls":["https://199.19.213.44:2380"]} {"level":"info","ts":"2026-03-24T14:47:26.474802Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-03-24T14:47:26.474649Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"4f2979fc0f638003","initial-advertise-peer-urls":["https://199.19.213.44:2380"],"listen-peer-urls":["https://199.19.213.44:2380"],"advertise-client-urls":["https://199.19.213.44:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.44:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-03-24T14:47:26.651539Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 is starting a new election at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.651614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became pre-candidate at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.651665Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgPreVoteResp from 4f2979fc0f638003 at term 1"} {"level":"info","ts":"2026-03-24T14:47:26.651685Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became candidate at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.65174Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 received MsgVoteResp from 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.651758Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"4f2979fc0f638003 became leader at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.651778Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 4f2979fc0f638003 elected leader 4f2979fc0f638003 at term 2"} {"level":"info","ts":"2026-03-24T14:47:26.654289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"4f2979fc0f638003","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.44:2379]}","request-path":"/0/members/4f2979fc0f638003/attributes","cluster-id":"ce06e7bc3b23612b","publish-timeout":"7s"} {"level":"info","ts":"2026-03-24T14:47:26.654348Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:47:26.654583Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.654673Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-03-24T14:47:26.65476Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-03-24T14:47:26.654647Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-03-24T14:47:26.657825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.44:2379"} {"level":"info","ts":"2026-03-24T14:47:26.658731Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-03-24T14:47:26.658938Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ce06e7bc3b23612b","local-member-id":"4f2979fc0f638003","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.65917Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-03-24T14:47:26.659274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-03-24T14:47:29.961183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.94511ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:29.961302Z","caller":"traceutil/trace.go:171","msg":"trace[1483083870] transaction","detail":"{read_only:false; response_revision:193; number_of_response:1; }","duration":"199.001653ms","start":"2026-03-24T14:47:29.762281Z","end":"2026-03-24T14:47:29.961283Z","steps":["trace[1483083870] 'process raft request' (duration: 30.334474ms)","trace[1483083870] 'compare' (duration: 167.830806ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:30.080392Z","caller":"traceutil/trace.go:171","msg":"trace[327561301] transaction","detail":"{read_only:false; response_revision:195; number_of_response:1; }","duration":"103.3665ms","start":"2026-03-24T14:47:29.977003Z","end":"2026-03-24T14:47:30.08037Z","steps":["trace[327561301] 'process raft request' (duration: 103.042877ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.183404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"664.955169ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:31.183513Z","caller":"traceutil/trace.go:171","msg":"trace[492845723] transaction","detail":"{read_only:false; response_revision:197; number_of_response:1; }","duration":"1.096455653s","start":"2026-03-24T14:47:30.087039Z","end":"2026-03-24T14:47:31.183495Z","steps":["trace[492845723] 'process raft request' (duration: 431.328067ms)","trace[492845723] 'compare' (duration: 664.818794ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:31.183573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.08703Z","time spent":"1.096516054s","remote":"127.0.0.1:53870","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:47:31.185066Z","caller":"traceutil/trace.go:171","msg":"trace[1713670631] linearizableReadLoop","detail":"{readStateIndex:204; appliedIndex:201; }","duration":"721.033238ms","start":"2026-03-24T14:47:30.464018Z","end":"2026-03-24T14:47:31.185051Z","steps":["trace[1713670631] 'read index received' (duration: 54.410543ms)","trace[1713670631] 'applied index is now lower than readState.Index' (duration: 666.621755ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:31.185206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.161922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:31.185252Z","caller":"traceutil/trace.go:171","msg":"trace[1224562759] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:199; }","duration":"721.264306ms","start":"2026-03-24T14:47:30.463975Z","end":"2026-03-24T14:47:31.18524Z","steps":["trace[1224562759] 'agreement among raft nodes before linearized reading' (duration: 721.133851ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:31.185247Z","caller":"traceutil/trace.go:171","msg":"trace[99654935] transaction","detail":"{read_only:false; response_revision:198; number_of_response:1; }","duration":"1.078129631s","start":"2026-03-24T14:47:30.107096Z","end":"2026-03-24T14:47:31.185226Z","steps":["trace[99654935] 'process raft request' (duration: 1.077842289s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.185289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.463959Z","time spent":"721.322939ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:47:31.185332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.107071Z","time spent":"1.078217054s","remote":"127.0.0.1:54154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:31.185653Z","caller":"traceutil/trace.go:171","msg":"trace[1426410934] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"1.041910104s","start":"2026-03-24T14:47:30.143728Z","end":"2026-03-24T14:47:31.185638Z","steps":["trace[1426410934] 'process raft request' (duration: 1.041266839s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:31.185722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:30.143705Z","time spent":"1.041984807s","remote":"127.0.0.1:54016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:47:32.032915Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389223901689071,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:47:32.322099Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.132572757s","expected-duration":"1s"} {"level":"info","ts":"2026-03-24T14:47:32.378734Z","caller":"traceutil/trace.go:171","msg":"trace[583381804] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"1.189260122s","start":"2026-03-24T14:47:31.189439Z","end":"2026-03-24T14:47:32.378699Z","steps":["trace[583381804] 'process raft request' (duration: 1.132970323s)","trace[583381804] 'compare' (duration: 56.087481ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:32.378844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"846.469226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:47:32.378853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.189417Z","time spent":"1.189382937s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-03-24T14:47:32.37888Z","caller":"traceutil/trace.go:171","msg":"trace[1867392958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:202; }","duration":"846.600001ms","start":"2026-03-24T14:47:31.532272Z","end":"2026-03-24T14:47:32.378872Z","steps":["trace[1867392958] 'agreement among raft nodes before linearized reading' (duration: 846.510198ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:32.37891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.532242Z","time spent":"846.660164ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-03-24T14:47:32.37874Z","caller":"traceutil/trace.go:171","msg":"trace[1059698430] linearizableReadLoop","detail":"{readStateIndex:207; appliedIndex:204; }","duration":"846.347881ms","start":"2026-03-24T14:47:31.532372Z","end":"2026-03-24T14:47:32.378719Z","steps":["trace[1059698430] 'read index received' (duration: 790.015751ms)","trace[1059698430] 'applied index is now lower than readState.Index' (duration: 56.33113ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:32.379094Z","caller":"traceutil/trace.go:171","msg":"trace[1740382064] transaction","detail":"{read_only:false; response_revision:201; number_of_response:1; }","duration":"1.187989631s","start":"2026-03-24T14:47:31.191095Z","end":"2026-03-24T14:47:32.379085Z","steps":["trace[1740382064] 'process raft request' (duration: 1.187543774s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:32.379162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.191083Z","time spent":"1.188037104s","remote":"127.0.0.1:53918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1342,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:47:32.379241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.601404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:32.379294Z","caller":"traceutil/trace.go:171","msg":"trace[1989524106] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:202; }","duration":"577.657725ms","start":"2026-03-24T14:47:31.801621Z","end":"2026-03-24T14:47:32.379279Z","steps":["trace[1989524106] 'agreement among raft nodes before linearized reading' (duration: 577.576732ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.379324Z","caller":"traceutil/trace.go:171","msg":"trace[2061214152] transaction","detail":"{read_only:false; response_revision:202; number_of_response:1; }","duration":"1.187614507s","start":"2026-03-24T14:47:31.191682Z","end":"2026-03-24T14:47:32.379296Z","steps":["trace[2061214152] 'process raft request' (duration: 1.187007223s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:32.379357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.801534Z","time spent":"577.813501ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:47:32.379388Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:31.191669Z","time spent":"1.18769066s","remote":"127.0.0.1:53984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":406,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:47:32.379486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.01872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:32.379525Z","caller":"traceutil/trace.go:171","msg":"trace[753757335] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:202; }","duration":"173.104232ms","start":"2026-03-24T14:47:32.206412Z","end":"2026-03-24T14:47:32.379516Z","steps":["trace[753757335] 'agreement among raft nodes before linearized reading' (duration: 173.046161ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.64177Z","caller":"traceutil/trace.go:171","msg":"trace[1600273819] transaction","detail":"{read_only:false; response_revision:203; number_of_response:1; }","duration":"258.485588ms","start":"2026-03-24T14:47:32.38325Z","end":"2026-03-24T14:47:32.641735Z","steps":["trace[1600273819] 'process raft request' (duration: 240.52679ms)","trace[1600273819] 'compare' (duration: 17.735539ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:32.642357Z","caller":"traceutil/trace.go:171","msg":"trace[645188007] transaction","detail":"{read_only:false; response_revision:204; number_of_response:1; }","duration":"258.996339ms","start":"2026-03-24T14:47:32.38334Z","end":"2026-03-24T14:47:32.642336Z","steps":["trace[645188007] 'process raft request' (duration: 258.611283ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.642561Z","caller":"traceutil/trace.go:171","msg":"trace[1424480376] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"258.150295ms","start":"2026-03-24T14:47:32.38437Z","end":"2026-03-24T14:47:32.642521Z","steps":["trace[1424480376] 'process raft request' (duration: 257.949838ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.642648Z","caller":"traceutil/trace.go:171","msg":"trace[1212058636] transaction","detail":"{read_only:false; response_revision:207; number_of_response:1; }","duration":"257.101483ms","start":"2026-03-24T14:47:32.385523Z","end":"2026-03-24T14:47:32.642625Z","steps":["trace[1212058636] 'process raft request' (duration: 256.849044ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.642672Z","caller":"traceutil/trace.go:171","msg":"trace[836403340] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"258.353752ms","start":"2026-03-24T14:47:32.384274Z","end":"2026-03-24T14:47:32.642627Z","steps":["trace[836403340] 'process raft request' (duration: 257.983778ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:32.642801Z","caller":"traceutil/trace.go:171","msg":"trace[1374225808] linearizableReadLoop","detail":"{readStateIndex:209; appliedIndex:207; }","duration":"259.228057ms","start":"2026-03-24T14:47:32.383419Z","end":"2026-03-24T14:47:32.642647Z","steps":["trace[1374225808] 'read index received' (duration: 240.358773ms)","trace[1374225808] 'applied index is now lower than readState.Index' (duration: 18.866684ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:32.643264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.803789ms","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-03-24T14:47:32.643343Z","caller":"traceutil/trace.go:171","msg":"trace[214446542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:207; }","duration":"259.917665ms","start":"2026-03-24T14:47:32.383398Z","end":"2026-03-24T14:47:32.643316Z","steps":["trace[214446542] 'agreement among raft nodes before linearized reading' (duration: 259.704337ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:33.886519Z","caller":"traceutil/trace.go:171","msg":"trace[296965945] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"120.624137ms","start":"2026-03-24T14:47:33.765869Z","end":"2026-03-24T14:47:33.886493Z","steps":["trace[296965945] 'process raft request' (duration: 79.206404ms)","trace[296965945] 'compare' (duration: 41.10642ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:37.599339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.732887ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:37.60031Z","caller":"traceutil/trace.go:171","msg":"trace[212771695] linearizableReadLoop","detail":"{readStateIndex:315; appliedIndex:314; }","duration":"195.510622ms","start":"2026-03-24T14:47:37.404726Z","end":"2026-03-24T14:47:37.600236Z","steps":["trace[212771695] 'read index received' (duration: 27.653542ms)","trace[212771695] 'applied index is now lower than readState.Index' (duration: 167.85307ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:37.600525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.791292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-24T14:47:37.600744Z","caller":"traceutil/trace.go:171","msg":"trace[1770162815] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:305; }","duration":"196.018321ms","start":"2026-03-24T14:47:37.40469Z","end":"2026-03-24T14:47:37.600708Z","steps":["trace[1770162815] 'agreement among raft nodes before linearized reading' (duration: 195.737381ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:37.600516Z","caller":"traceutil/trace.go:171","msg":"trace[2069215964] transaction","detail":"{read_only:false; response_revision:305; number_of_response:1; }","duration":"217.250681ms","start":"2026-03-24T14:47:37.383199Z","end":"2026-03-24T14:47:37.600449Z","steps":["trace[2069215964] 'process raft request' (duration: 49.223945ms)","trace[2069215964] 'compare' (duration: 166.069029ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:37.602002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.735416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-03-24T14:47:37.602078Z","caller":"traceutil/trace.go:171","msg":"trace[1757824416] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:305; }","duration":"147.879391ms","start":"2026-03-24T14:47:37.454176Z","end":"2026-03-24T14:47:37.602055Z","steps":["trace[1757824416] 'agreement among raft nodes before linearized reading' (duration: 146.787597ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:37.60244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.934724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/bootstrap-token-paigy1\" ","response":"range_response_count:1 size:540"} {"level":"info","ts":"2026-03-24T14:47:37.602603Z","caller":"traceutil/trace.go:171","msg":"trace[1172085258] range","detail":"{range_begin:/registry/secrets/kube-system/bootstrap-token-paigy1; range_end:; response_count:1; response_revision:305; }","duration":"144.083961ms","start":"2026-03-24T14:47:37.458461Z","end":"2026-03-24T14:47:37.602545Z","steps":["trace[1172085258] 'agreement among raft nodes before linearized reading' (duration: 143.867542ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:38.727053Z","caller":"traceutil/trace.go:171","msg":"trace[1735137053] linearizableReadLoop","detail":"{readStateIndex:362; appliedIndex:361; }","duration":"109.912112ms","start":"2026-03-24T14:47:38.617107Z","end":"2026-03-24T14:47:38.727019Z","steps":["trace[1735137053] 'read index received' (duration: 31.870849ms)","trace[1735137053] 'applied index is now lower than readState.Index' (duration: 78.039723ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:38.727075Z","caller":"traceutil/trace.go:171","msg":"trace[513644649] transaction","detail":"{read_only:false; response_revision:352; number_of_response:1; }","duration":"144.703926ms","start":"2026-03-24T14:47:38.58232Z","end":"2026-03-24T14:47:38.727024Z","steps":["trace[513644649] 'process raft request' (duration: 66.715315ms)","trace[513644649] 'compare' (duration: 77.793104ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:38.727249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.103259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-03-24T14:47:38.727279Z","caller":"traceutil/trace.go:171","msg":"trace[1176067256] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:352; }","duration":"110.197954ms","start":"2026-03-24T14:47:38.61707Z","end":"2026-03-24T14:47:38.727268Z","steps":["trace[1176067256] 'agreement among raft nodes before linearized reading' (duration: 110.059378ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:39.632667Z","caller":"traceutil/trace.go:171","msg":"trace[403704398] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"246.746222ms","start":"2026-03-24T14:47:39.385868Z","end":"2026-03-24T14:47:39.632614Z","steps":["trace[403704398] 'process raft request' (duration: 246.527654ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:39.768388Z","caller":"traceutil/trace.go:171","msg":"trace[830691096] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"129.29994ms","start":"2026-03-24T14:47:39.639045Z","end":"2026-03-24T14:47:39.768345Z","steps":["trace[830691096] 'process raft request' (duration: 126.790211ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:44.461666Z","caller":"traceutil/trace.go:171","msg":"trace[1398238802] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"106.118303ms","start":"2026-03-24T14:47:44.355523Z","end":"2026-03-24T14:47:44.461642Z","steps":["trace[1398238802] 'process raft request' (duration: 105.955416ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:47.185897Z","caller":"traceutil/trace.go:171","msg":"trace[1842521128] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:393; }","duration":"262.289095ms","start":"2026-03-24T14:47:46.923592Z","end":"2026-03-24T14:47:47.185881Z","steps":["trace[1842521128] 'read index received' (duration: 42.471673ms)","trace[1842521128] 'applied index is now lower than readState.Index' (duration: 219.816742ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:47.185944Z","caller":"traceutil/trace.go:171","msg":"trace[1229356858] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"264.229543ms","start":"2026-03-24T14:47:46.921702Z","end":"2026-03-24T14:47:47.185931Z","steps":["trace[1229356858] 'process raft request' (duration: 201.518606ms)","trace[1229356858] 'compare' (duration: 62.509158ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:47.185974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.369478ms","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-03-24T14:47:47.185992Z","caller":"traceutil/trace.go:171","msg":"trace[911096535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:381; }","duration":"262.398569ms","start":"2026-03-24T14:47:46.923587Z","end":"2026-03-24T14:47:47.185986Z","steps":["trace[911096535] 'agreement among raft nodes before linearized reading' (duration: 262.354488ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:47.639408Z","caller":"traceutil/trace.go:171","msg":"trace[514930620] linearizableReadLoop","detail":"{readStateIndex:396; appliedIndex:395; }","duration":"123.379131ms","start":"2026-03-24T14:47:47.516006Z","end":"2026-03-24T14:47:47.639385Z","steps":["trace[514930620] 'read index received' (duration: 123.190674ms)","trace[514930620] 'applied index is now lower than readState.Index' (duration: 187.637µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:47.639508Z","caller":"traceutil/trace.go:171","msg":"trace[1988031498] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"249.73057ms","start":"2026-03-24T14:47:47.389743Z","end":"2026-03-24T14:47:47.639474Z","steps":["trace[1988031498] 'process raft request' (duration: 249.510491ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:47.639535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.506136ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:47.639594Z","caller":"traceutil/trace.go:171","msg":"trace[866359239] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:383; }","duration":"123.6108ms","start":"2026-03-24T14:47:47.51597Z","end":"2026-03-24T14:47:47.63958Z","steps":["trace[866359239] 'agreement among raft nodes before linearized reading' (duration: 123.513276ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:49.156326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.753297ms","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-03-24T14:47:49.15639Z","caller":"traceutil/trace.go:171","msg":"trace[753914730] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:384; }","duration":"186.858961ms","start":"2026-03-24T14:47:48.969516Z","end":"2026-03-24T14:47:49.156375Z","steps":["trace[753914730] 'range keys from in-memory index tree' (duration: 186.598101ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:49.156327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.845421ms","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-03-24T14:47:49.156477Z","caller":"traceutil/trace.go:171","msg":"trace[560075554] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:384; }","duration":"187.037599ms","start":"2026-03-24T14:47:48.969421Z","end":"2026-03-24T14:47:49.156459Z","steps":["trace[560075554] 'range keys from in-memory index tree' (duration: 186.754387ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:49.769926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"303.015661ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:49.770038Z","caller":"traceutil/trace.go:171","msg":"trace[1238287195] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"537.753031ms","start":"2026-03-24T14:47:49.232267Z","end":"2026-03-24T14:47:49.77002Z","steps":["trace[1238287195] 'process raft request' (duration: 234.554924ms)","trace[1238287195] 'compare' (duration: 302.900935ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:49.770098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:49.232232Z","time spent":"537.838665ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:47:49.780652Z","caller":"traceutil/trace.go:171","msg":"trace[1404612492] linearizableReadLoop","detail":"{readStateIndex:403; appliedIndex:401; }","duration":"265.97832ms","start":"2026-03-24T14:47:49.514652Z","end":"2026-03-24T14:47:49.78063Z","steps":["trace[1404612492] 'read index received' (duration: 265.761811ms)","trace[1404612492] 'applied index is now lower than readState.Index' (duration: 215.699µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:49.780681Z","caller":"traceutil/trace.go:171","msg":"trace[1290439310] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"548.04742ms","start":"2026-03-24T14:47:49.232612Z","end":"2026-03-24T14:47:49.780659Z","steps":["trace[1290439310] 'process raft request' (duration: 547.850992ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:49.780827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:49.232596Z","time spent":"548.139053ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:47:49.780854Z","caller":"traceutil/trace.go:171","msg":"trace[932415922] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"546.384884ms","start":"2026-03-24T14:47:49.234444Z","end":"2026-03-24T14:47:49.780829Z","steps":["trace[932415922] 'process raft request' (duration: 546.137894ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:49.780979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:47:49.234417Z","time spent":"546.486737ms","remote":"127.0.0.1:54310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3678,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-03-24T14:47:49.780914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.22002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:49.781055Z","caller":"traceutil/trace.go:171","msg":"trace[609415580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:390; }","duration":"266.423349ms","start":"2026-03-24T14:47:49.514606Z","end":"2026-03-24T14:47:49.781029Z","steps":["trace[609415580] 'agreement among raft nodes before linearized reading' (duration: 266.161929ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:49.959499Z","caller":"traceutil/trace.go:171","msg":"trace[1943738490] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"138.634539ms","start":"2026-03-24T14:47:49.820829Z","end":"2026-03-24T14:47:49.959464Z","steps":["trace[1943738490] 'process raft request' (duration: 138.556886ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:49.959517Z","caller":"traceutil/trace.go:171","msg":"trace[2143586601] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"168.809726ms","start":"2026-03-24T14:47:49.79058Z","end":"2026-03-24T14:47:49.95939Z","steps":["trace[2143586601] 'process raft request' (duration: 81.281964ms)","trace[2143586601] 'compare' (duration: 87.264122ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:49.959512Z","caller":"traceutil/trace.go:171","msg":"trace[1612657462] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"168.745134ms","start":"2026-03-24T14:47:49.790712Z","end":"2026-03-24T14:47:49.959457Z","steps":["trace[1612657462] 'process raft request' (duration: 168.567557ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:49.959773Z","caller":"traceutil/trace.go:171","msg":"trace[1991794604] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"161.310258ms","start":"2026-03-24T14:47:49.79843Z","end":"2026-03-24T14:47:49.959741Z","steps":["trace[1991794604] 'process raft request' (duration: 160.896432ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:50.218318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.198605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:47:50.218991Z","caller":"traceutil/trace.go:171","msg":"trace[356148343] linearizableReadLoop","detail":"{readStateIndex:409; appliedIndex:408; }","duration":"252.084182ms","start":"2026-03-24T14:47:49.966878Z","end":"2026-03-24T14:47:50.218962Z","steps":["trace[356148343] 'read index received' (duration: 117.176539ms)","trace[356148343] 'applied index is now lower than readState.Index' (duration: 134.905693ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:50.219047Z","caller":"traceutil/trace.go:171","msg":"trace[159875296] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"252.440486ms","start":"2026-03-24T14:47:49.966571Z","end":"2026-03-24T14:47:50.219012Z","steps":["trace[159875296] 'process raft request' (duration: 117.388488ms)","trace[159875296] 'compare' (duration: 134.012567ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:47:50.219138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.234098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:132"} {"level":"info","ts":"2026-03-24T14:47:50.219475Z","caller":"traceutil/trace.go:171","msg":"trace[520767650] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:395; }","duration":"252.588392ms","start":"2026-03-24T14:47:49.966863Z","end":"2026-03-24T14:47:50.219452Z","steps":["trace[520767650] 'agreement among raft nodes before linearized reading' (duration: 252.186995ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.245831Z","caller":"traceutil/trace.go:171","msg":"trace[1882682656] transaction","detail":"{read_only:false; response_revision:396; number_of_response:1; }","duration":"278.835954ms","start":"2026-03-24T14:47:49.966971Z","end":"2026-03-24T14:47:50.245807Z","steps":["trace[1882682656] 'process raft request' (duration: 278.650986ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.245828Z","caller":"traceutil/trace.go:171","msg":"trace[1256877935] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"277.128056ms","start":"2026-03-24T14:47:49.968667Z","end":"2026-03-24T14:47:50.245795Z","steps":["trace[1256877935] 'process raft request' (duration: 277.032283ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.245919Z","caller":"traceutil/trace.go:171","msg":"trace[1777505873] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"272.861127ms","start":"2026-03-24T14:47:49.973032Z","end":"2026-03-24T14:47:50.245893Z","steps":["trace[1777505873] 'process raft request' (duration: 272.737862ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.245855Z","caller":"traceutil/trace.go:171","msg":"trace[176023058] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"275.605466ms","start":"2026-03-24T14:47:49.970231Z","end":"2026-03-24T14:47:50.245836Z","steps":["trace[176023058] 'process raft request' (duration: 275.498371ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:50.499061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.457079ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:47:50.499203Z","caller":"traceutil/trace.go:171","msg":"trace[567927925] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"248.023712ms","start":"2026-03-24T14:47:50.251148Z","end":"2026-03-24T14:47:50.499172Z","steps":["trace[567927925] 'process raft request' (duration: 176.606708ms)","trace[567927925] 'compare' (duration: 71.148144ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:50.499236Z","caller":"traceutil/trace.go:171","msg":"trace[276573564] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:400; }","duration":"124.667347ms","start":"2026-03-24T14:47:50.374541Z","end":"2026-03-24T14:47:50.499208Z","steps":["trace[276573564] 'range keys from in-memory index tree' (duration: 124.429347ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.49967Z","caller":"traceutil/trace.go:171","msg":"trace[1373000922] linearizableReadLoop","detail":"{readStateIndex:416; appliedIndex:413; }","duration":"244.648559ms","start":"2026-03-24T14:47:50.255005Z","end":"2026-03-24T14:47:50.499653Z","steps":["trace[1373000922] 'read index received' (duration: 30.690778ms)","trace[1373000922] 'applied index is now lower than readState.Index' (duration: 213.956491ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:50.499695Z","caller":"traceutil/trace.go:171","msg":"trace[1113066560] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"248.530272ms","start":"2026-03-24T14:47:50.251149Z","end":"2026-03-24T14:47:50.499679Z","steps":["trace[1113066560] 'process raft request' (duration: 247.9538ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:47:50.49979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.752813ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-03-24T14:47:50.499834Z","caller":"traceutil/trace.go:171","msg":"trace[1349213916] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:402; }","duration":"244.841735ms","start":"2026-03-24T14:47:50.254979Z","end":"2026-03-24T14:47:50.49982Z","steps":["trace[1349213916] 'agreement among raft nodes before linearized reading' (duration: 244.737821ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.6849Z","caller":"traceutil/trace.go:171","msg":"trace[663073092] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"176.959291ms","start":"2026-03-24T14:47:50.507918Z","end":"2026-03-24T14:47:50.684877Z","steps":["trace[663073092] 'process raft request' (duration: 176.891938ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.685072Z","caller":"traceutil/trace.go:171","msg":"trace[199352543] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"179.94806ms","start":"2026-03-24T14:47:50.505052Z","end":"2026-03-24T14:47:50.685Z","steps":["trace[199352543] 'process raft request' (duration: 134.251076ms)","trace[199352543] 'compare' (duration: 45.346561ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:50.689535Z","caller":"traceutil/trace.go:171","msg":"trace[1792025211] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"144.39045ms","start":"2026-03-24T14:47:50.545111Z","end":"2026-03-24T14:47:50.689502Z","steps":["trace[1792025211] 'process raft request' (duration: 144.333147ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:50.689865Z","caller":"traceutil/trace.go:171","msg":"trace[4316838] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"144.958282ms","start":"2026-03-24T14:47:50.5449Z","end":"2026-03-24T14:47:50.689858Z","steps":["trace[4316838] 'process raft request' (duration: 144.355538ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:47:52.032779Z","caller":"traceutil/trace.go:171","msg":"trace[1151123443] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"163.070013ms","start":"2026-03-24T14:47:51.869681Z","end":"2026-03-24T14:47:52.032751Z","steps":["trace[1151123443] 'process raft request' (duration: 108.156813ms)","trace[1151123443] 'compare' (duration: 54.803966ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:47:55.09744Z","caller":"traceutil/trace.go:171","msg":"trace[96797262] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"165.270157ms","start":"2026-03-24T14:47:54.932144Z","end":"2026-03-24T14:47:55.097414Z","steps":["trace[96797262] 'process raft request' (duration: 165.10381ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:03.579357Z","caller":"traceutil/trace.go:171","msg":"trace[55620121] linearizableReadLoop","detail":"{readStateIndex:497; appliedIndex:496; }","duration":"234.018541ms","start":"2026-03-24T14:48:03.345322Z","end":"2026-03-24T14:48:03.579341Z","steps":["trace[55620121] 'read index received' (duration: 233.876075ms)","trace[55620121] 'applied index is now lower than readState.Index' (duration: 141.866µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:48:03.579572Z","caller":"traceutil/trace.go:171","msg":"trace[898879942] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"254.357761ms","start":"2026-03-24T14:48:03.325206Z","end":"2026-03-24T14:48:03.579564Z","steps":["trace[898879942] 'process raft request' (duration: 254.041708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:03.579753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.417686ms","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-03-24T14:48:03.579782Z","caller":"traceutil/trace.go:171","msg":"trace[455944125] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"234.483381ms","start":"2026-03-24T14:48:03.34529Z","end":"2026-03-24T14:48:03.579774Z","steps":["trace[455944125] 'agreement among raft nodes before linearized reading' (duration: 234.389777ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.013683Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389223901689662,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-03-24T14:48:06.46239Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.079692631s","expected-duration":"1s"} {"level":"warn","ts":"2026-03-24T14:48:06.783128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.400390684s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-24T14:48:06.783298Z","caller":"traceutil/trace.go:171","msg":"trace[172938370] linearizableReadLoop","detail":"{readStateIndex:503; appliedIndex:501; }","duration":"1.269769859s","start":"2026-03-24T14:48:05.513517Z","end":"2026-03-24T14:48:06.783286Z","steps":["trace[172938370] 'read index received' (duration: 948.995662ms)","trace[172938370] 'applied index is now lower than readState.Index' (duration: 320.773517ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:06.783371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.269847303s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:06.783392Z","caller":"traceutil/trace.go:171","msg":"trace[89697847] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:486; }","duration":"1.269872823s","start":"2026-03-24T14:48:05.513512Z","end":"2026-03-24T14:48:06.783385Z","steps":["trace[89697847] 'agreement among raft nodes before linearized reading' (duration: 1.269828092s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.513481Z","time spent":"1.269926116s","remote":"127.0.0.1:53852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-03-24T14:48:06.783365Z","caller":"traceutil/trace.go:171","msg":"trace[49787624] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"1.535769759s","start":"2026-03-24T14:48:05.247564Z","end":"2026-03-24T14:48:06.783333Z","steps":["trace[49787624] 'process raft request' (duration: 1.535645674s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.199836612s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-24T14:48:06.783537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.62175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:06.783624Z","caller":"traceutil/trace.go:171","msg":"trace[743758216] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:0; response_revision:486; }","duration":"540.721533ms","start":"2026-03-24T14:48:06.242883Z","end":"2026-03-24T14:48:06.783604Z","steps":["trace[743758216] 'agreement among raft nodes before linearized reading' (duration: 540.594458ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:06.783621Z","caller":"traceutil/trace.go:171","msg":"trace[239623701] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:486; }","duration":"1.199953067s","start":"2026-03-24T14:48:05.583643Z","end":"2026-03-24T14:48:06.783596Z","steps":["trace[239623701] 'agreement among raft nodes before linearized reading' (duration: 1.199788431s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"651.210478ms","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-03-24T14:48:06.783671Z","caller":"traceutil/trace.go:171","msg":"trace[1808139648] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:486; }","duration":"651.27617ms","start":"2026-03-24T14:48:06.132387Z","end":"2026-03-24T14:48:06.783664Z","steps":["trace[1808139648] 'agreement among raft nodes before linearized reading' (duration: 651.052281ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.132362Z","time spent":"651.318882ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:48:06.783677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.583629Z","time spent":"1.2000349s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:48:06.783696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:06.242825Z","time spent":"540.836289ms","remote":"127.0.0.1:53916","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-03-24T14:48:06.78371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.192479299s","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-03-24T14:48:06.783758Z","caller":"traceutil/trace.go:171","msg":"trace[456692642] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:486; }","duration":"1.192534231s","start":"2026-03-24T14:48:05.591209Z","end":"2026-03-24T14:48:06.783743Z","steps":["trace[456692642] 'agreement among raft nodes before linearized reading' (duration: 1.19224534s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.591204Z","time spent":"1.192577322s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:06.783908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.760601ms","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-03-24T14:48:06.783962Z","caller":"traceutil/trace.go:171","msg":"trace[1252050512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:486; }","duration":"855.858615ms","start":"2026-03-24T14:48:05.92809Z","end":"2026-03-24T14:48:06.783949Z","steps":["trace[1252050512] 'agreement among raft nodes before linearized reading' (duration: 855.354384ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:06.783998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.928068Z","time spent":"855.923397ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:06.784144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:05.247535Z","time spent":"1.535985599s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:10.706792Z","caller":"traceutil/trace.go:171","msg":"trace[1382935300] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"237.203755ms","start":"2026-03-24T14:48:10.469571Z","end":"2026-03-24T14:48:10.706775Z","steps":["trace[1382935300] 'process raft request' (duration: 237.080989ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.42462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.857567ms","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-03-24T14:48:11.424706Z","caller":"traceutil/trace.go:171","msg":"trace[68281395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"615.981561ms","start":"2026-03-24T14:48:10.808703Z","end":"2026-03-24T14:48:11.424685Z","steps":["trace[68281395] 'range keys from in-memory index tree' (duration: 615.71448ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.424749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.808694Z","time spent":"616.042213ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:48:11.424765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"614.812734ms","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-03-24T14:48:11.424838Z","caller":"traceutil/trace.go:171","msg":"trace[825436071] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:522; }","duration":"614.913899ms","start":"2026-03-24T14:48:10.809904Z","end":"2026-03-24T14:48:11.424817Z","steps":["trace[825436071] 'range keys from in-memory index tree' (duration: 614.670728ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.424905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.809894Z","time spent":"615.000712ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:11.425034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"620.71264ms","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-03-24T14:48:11.425071Z","caller":"traceutil/trace.go:171","msg":"trace[1222227145] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"620.771722ms","start":"2026-03-24T14:48:10.804288Z","end":"2026-03-24T14:48:11.425059Z","steps":["trace[1222227145] 'range keys from in-memory index tree' (duration: 620.590515ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.425096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.80426Z","time spent":"620.829544ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:11.425132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"617.22805ms","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-03-24T14:48:11.42517Z","caller":"traceutil/trace.go:171","msg":"trace[841384204] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:522; }","duration":"617.305603ms","start":"2026-03-24T14:48:10.807857Z","end":"2026-03-24T14:48:11.425162Z","steps":["trace[841384204] 'range keys from in-memory index tree' (duration: 617.123797ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:11.42519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:10.807807Z","time spent":"617.378486ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-03-24T14:48:11.702767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.195745ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:48:11.702929Z","caller":"traceutil/trace.go:171","msg":"trace[1969678496] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"273.601049ms","start":"2026-03-24T14:48:11.429299Z","end":"2026-03-24T14:48:11.7029Z","steps":["trace[1969678496] 'process raft request' (duration: 135.183525ms)","trace[1969678496] 'compare' (duration: 138.046529ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:48:11.703013Z","caller":"traceutil/trace.go:171","msg":"trace[1637717820] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"273.643631ms","start":"2026-03-24T14:48:11.429349Z","end":"2026-03-24T14:48:11.702992Z","steps":["trace[1637717820] 'process raft request' (duration: 273.527907ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:11.70306Z","caller":"traceutil/trace.go:171","msg":"trace[1140844976] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:541; }","duration":"186.94052ms","start":"2026-03-24T14:48:11.516108Z","end":"2026-03-24T14:48:11.703048Z","steps":["trace[1140844976] 'read index received' (duration: 48.402382ms)","trace[1140844976] 'applied index is now lower than readState.Index' (duration: 138.537608ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:11.703135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.020004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:11.703156Z","caller":"traceutil/trace.go:171","msg":"trace[1394095312] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"187.090896ms","start":"2026-03-24T14:48:11.516059Z","end":"2026-03-24T14:48:11.70315Z","steps":["trace[1394095312] 'agreement among raft nodes before linearized reading' (duration: 187.026164ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:11.703222Z","caller":"traceutil/trace.go:171","msg":"trace[1947430577] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"273.707064ms","start":"2026-03-24T14:48:11.429507Z","end":"2026-03-24T14:48:11.703214Z","steps":["trace[1947430577] 'process raft request' (duration: 273.437833ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.515145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80838521s","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-03-24T14:48:15.515257Z","caller":"traceutil/trace.go:171","msg":"trace[1357353321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.808513413s","start":"2026-03-24T14:48:13.70672Z","end":"2026-03-24T14:48:15.515233Z","steps":["trace[1357353321] 'range keys from in-memory index tree' (duration: 1.808264048s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.515428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:13.706692Z","time spent":"1.808719364s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:15.51563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.028555703s","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-03-24T14:48:15.515697Z","caller":"traceutil/trace.go:171","msg":"trace[1991902677] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:526; }","duration":"2.028658305s","start":"2026-03-24T14:48:13.487023Z","end":"2026-03-24T14:48:15.515681Z","steps":["trace[1991902677] 'range keys from in-memory index tree' (duration: 2.028429321s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.515722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:13.487008Z","time spent":"2.028708535s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:48:15.515663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.999560726s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:48:15.515795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.809269s","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-03-24T14:48:15.515803Z","caller":"traceutil/trace.go:171","msg":"trace[1441919369] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"2.999761589s","start":"2026-03-24T14:48:12.516007Z","end":"2026-03-24T14:48:15.515768Z","steps":["trace[1441919369] 'range keys from in-memory index tree' (duration: 2.999419641s)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:15.515848Z","caller":"traceutil/trace.go:171","msg":"trace[1158271770] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:526; }","duration":"1.809365911s","start":"2026-03-24T14:48:13.706467Z","end":"2026-03-24T14:48:15.515833Z","steps":["trace[1158271770] 'range keys from in-memory index tree' (duration: 1.80917792s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.515872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:12.515988Z","time spent":"2.99986754s","remote":"127.0.0.1:53852","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-03-24T14:48:15.515896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.493714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:48:15.515894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:13.706442Z","time spent":"1.809439462s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-03-24T14:48:15.515915Z","caller":"traceutil/trace.go:171","msg":"trace[67544342] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:526; }","duration":"998.548694ms","start":"2026-03-24T14:48:14.51736Z","end":"2026-03-24T14:48:15.515909Z","steps":["trace[67544342] 'range keys from in-memory index tree' (duration: 998.431233ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.515933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:14.517345Z","time spent":"998.585094ms","remote":"127.0.0.1:53854","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-03-24T14:48:15.515145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.80841499s","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-03-24T14:48:15.516094Z","caller":"traceutil/trace.go:171","msg":"trace[164260814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:526; }","duration":"1.809404732s","start":"2026-03-24T14:48:13.706653Z","end":"2026-03-24T14:48:15.516057Z","steps":["trace[164260814] 'range keys from in-memory index tree' (duration: 1.808260408s)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:15.516204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:13.706624Z","time spent":"1.809544493s","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-03-24T14:48:15.561805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.420312ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-03-24T14:48:17.658164Z","caller":"traceutil/trace.go:171","msg":"trace[800962799] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:554; }","duration":"178.325171ms","start":"2026-03-24T14:48:17.479814Z","end":"2026-03-24T14:48:17.658139Z","steps":["trace[800962799] 'read index received' (duration: 150.831632ms)","trace[800962799] 'applied index is now lower than readState.Index' (duration: 27.492219ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:48:17.658381Z","caller":"traceutil/trace.go:171","msg":"trace[1394603723] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"742.382777ms","start":"2026-03-24T14:48:16.915936Z","end":"2026-03-24T14:48:17.658319Z","steps":["trace[1394603723] 'process raft request' (duration: 714.672464ms)","trace[1394603723] 'compare' (duration: 27.117345ms)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:17.658451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.278414ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:17.65854Z","caller":"traceutil/trace.go:171","msg":"trace[79750975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"141.404565ms","start":"2026-03-24T14:48:17.517114Z","end":"2026-03-24T14:48:17.658519Z","steps":["trace[79750975] 'agreement among raft nodes before linearized reading' (duration: 141.281444ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:17.658819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.007469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9wzrj\" ","response":"range_response_count:1 size:17243"} {"level":"info","ts":"2026-03-24T14:48:17.658865Z","caller":"traceutil/trace.go:171","msg":"trace[631960110] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9wzrj; range_end:; response_count:1; response_revision:536; }","duration":"179.079111ms","start":"2026-03-24T14:48:17.479774Z","end":"2026-03-24T14:48:17.658854Z","steps":["trace[631960110] 'agreement among raft nodes before linearized reading' (duration: 178.9802ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:17.658483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:48:16.915913Z","time spent":"742.502857ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:48:21.835384Z","caller":"traceutil/trace.go:171","msg":"trace[1760855462] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"152.243867ms","start":"2026-03-24T14:48:21.683076Z","end":"2026-03-24T14:48:21.83532Z","steps":["trace[1760855462] 'process raft request' (duration: 152.019724ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:21.835634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.118344ms","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-03-24T14:48:21.835712Z","caller":"traceutil/trace.go:171","msg":"trace[913357354] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:608; }","duration":"139.238036ms","start":"2026-03-24T14:48:21.696449Z","end":"2026-03-24T14:48:21.835687Z","steps":["trace[913357354] 'agreement among raft nodes before linearized reading' (duration: 139.008703ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:21.835262Z","caller":"traceutil/trace.go:171","msg":"trace[59909411] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:627; }","duration":"138.728899ms","start":"2026-03-24T14:48:21.696498Z","end":"2026-03-24T14:48:21.835227Z","steps":["trace[59909411] 'read index received' (duration: 138.534846ms)","trace[59909411] 'applied index is now lower than readState.Index' (duration: 193.043µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:21.840989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.710394ms","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-03-24T14:48:21.840987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.042498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-03-24T14:48:21.840997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.329165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-03-24T14:48:21.841023Z","caller":"traceutil/trace.go:171","msg":"trace[330614736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:609; }","duration":"130.784145ms","start":"2026-03-24T14:48:21.710231Z","end":"2026-03-24T14:48:21.841015Z","steps":["trace[330614736] 'agreement among raft nodes before linearized reading' (duration: 130.680214ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:21.841036Z","caller":"traceutil/trace.go:171","msg":"trace[1252314112] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:609; }","duration":"144.13672ms","start":"2026-03-24T14:48:21.696887Z","end":"2026-03-24T14:48:21.841023Z","steps":["trace[1252314112] 'agreement among raft nodes before linearized reading' (duration: 143.994318ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:21.841044Z","caller":"traceutil/trace.go:171","msg":"trace[250239590] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:609; }","duration":"119.408526ms","start":"2026-03-24T14:48:21.721625Z","end":"2026-03-24T14:48:21.841034Z","steps":["trace[250239590] 'agreement among raft nodes before linearized reading' (duration: 119.267964ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:21.841211Z","caller":"traceutil/trace.go:171","msg":"trace[1127759259] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"118.633315ms","start":"2026-03-24T14:48:21.722567Z","end":"2026-03-24T14:48:21.8412Z","steps":["trace[1127759259] 'process raft request' (duration: 118.149439ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:32.028546Z","caller":"traceutil/trace.go:171","msg":"trace[386775560] transaction","detail":"{read_only:false; response_revision:721; number_of_response:1; }","duration":"109.442138ms","start":"2026-03-24T14:48:31.919085Z","end":"2026-03-24T14:48:32.028527Z","steps":["trace[386775560] 'process raft request' (duration: 108.456174ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:34.191053Z","caller":"traceutil/trace.go:171","msg":"trace[749809227] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"272.1407ms","start":"2026-03-24T14:48:33.918881Z","end":"2026-03-24T14:48:34.191022Z","steps":["trace[749809227] 'read index received' (duration: 271.907877ms)","trace[749809227] 'applied index is now lower than readState.Index' (duration: 231.733µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:48:34.191392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.061776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-03-24T14:48:34.191459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.435373ms","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-03-24T14:48:34.191494Z","caller":"traceutil/trace.go:171","msg":"trace[932237360] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:723; }","duration":"157.540974ms","start":"2026-03-24T14:48:34.033943Z","end":"2026-03-24T14:48:34.191484Z","steps":["trace[932237360] 'agreement among raft nodes before linearized reading' (duration: 157.346572ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:34.191466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.530817ms","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-03-24T14:48:34.191524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.757652ms","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-03-24T14:48:34.191138Z","caller":"traceutil/trace.go:171","msg":"trace[1447814650] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"278.304856ms","start":"2026-03-24T14:48:33.912783Z","end":"2026-03-24T14:48:34.191088Z","steps":["trace[1447814650] 'process raft request' (duration: 278.085983ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:34.191614Z","caller":"traceutil/trace.go:171","msg":"trace[1983339123] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:723; }","duration":"272.74128ms","start":"2026-03-24T14:48:33.918831Z","end":"2026-03-24T14:48:34.191572Z","steps":["trace[1983339123] 'agreement among raft nodes before linearized reading' (duration: 272.336133ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:34.191504Z","caller":"traceutil/trace.go:171","msg":"trace[1042589919] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:723; }","duration":"239.42486ms","start":"2026-03-24T14:48:33.952051Z","end":"2026-03-24T14:48:34.191476Z","steps":["trace[1042589919] 'agreement among raft nodes before linearized reading' (duration: 239.159447ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:34.191623Z","caller":"traceutil/trace.go:171","msg":"trace[310828490] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:723; }","duration":"143.907694ms","start":"2026-03-24T14:48:34.047692Z","end":"2026-03-24T14:48:34.191599Z","steps":["trace[310828490] 'agreement among raft nodes before linearized reading' (duration: 143.565559ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:48:35.023267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.131672ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"warn","ts":"2026-03-24T14:48:38.544082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.630382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:48:38.544142Z","caller":"traceutil/trace.go:171","msg":"trace[154876518] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"134.757413ms","start":"2026-03-24T14:48:38.409371Z","end":"2026-03-24T14:48:38.544128Z","steps":["trace[154876518] 'range keys from in-memory index tree' (duration: 134.527999ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:38.743988Z","caller":"traceutil/trace.go:171","msg":"trace[1263799706] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"136.759776ms","start":"2026-03-24T14:48:38.607172Z","end":"2026-03-24T14:48:38.743932Z","steps":["trace[1263799706] 'process raft request' (duration: 136.609483ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:48:40.054523Z","caller":"traceutil/trace.go:171","msg":"trace[718205435] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"205.102291ms","start":"2026-03-24T14:48:39.849395Z","end":"2026-03-24T14:48:40.054498Z","steps":["trace[718205435] 'process raft request' (duration: 201.502462ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:30.546835Z","caller":"traceutil/trace.go:171","msg":"trace[869378108] transaction","detail":"{read_only:false; response_revision:902; number_of_response:1; }","duration":"135.005473ms","start":"2026-03-24T14:49:30.411771Z","end":"2026-03-24T14:49:30.546777Z","steps":["trace[869378108] 'process raft request' (duration: 134.83466ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:38.936652Z","caller":"traceutil/trace.go:171","msg":"trace[1499761311] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"299.147629ms","start":"2026-03-24T14:49:38.636809Z","end":"2026-03-24T14:49:38.935957Z","steps":["trace[1499761311] 'process raft request' (duration: 298.955625ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:38.935949Z","caller":"traceutil/trace.go:171","msg":"trace[1975335497] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"297.122121ms","start":"2026-03-24T14:49:38.638784Z","end":"2026-03-24T14:49:38.935906Z","steps":["trace[1975335497] 'read index received' (duration: 296.881936ms)","trace[1975335497] 'applied index is now lower than readState.Index' (duration: 239.155µs)"],"step_count":2} {"level":"warn","ts":"2026-03-24T14:49:38.937219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.524594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"warn","ts":"2026-03-24T14:49:38.937454Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.714081ms","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-03-24T14:49:38.937458Z","caller":"traceutil/trace.go:171","msg":"trace[1969275175] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:941; }","duration":"224.817941ms","start":"2026-03-24T14:49:38.712612Z","end":"2026-03-24T14:49:38.93743Z","steps":["trace[1969275175] 'agreement among raft nodes before linearized reading' (duration: 224.441932ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:38.937671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.775691ms","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-03-24T14:49:38.937753Z","caller":"traceutil/trace.go:171","msg":"trace[509539586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"121.891674ms","start":"2026-03-24T14:49:38.815828Z","end":"2026-03-24T14:49:38.93772Z","steps":["trace[509539586] 'agreement among raft nodes before linearized reading' (duration: 121.565436ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:38.937703Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.532861ms","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-03-24T14:49:38.937563Z","caller":"traceutil/trace.go:171","msg":"trace[453715217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"114.832383ms","start":"2026-03-24T14:49:38.822683Z","end":"2026-03-24T14:49:38.937516Z","steps":["trace[453715217] 'agreement among raft nodes before linearized reading' (duration: 114.469654ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:38.938122Z","caller":"traceutil/trace.go:171","msg":"trace[83325224] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"115.972041ms","start":"2026-03-24T14:49:38.821962Z","end":"2026-03-24T14:49:38.937934Z","steps":["trace[83325224] 'agreement among raft nodes before linearized reading' (duration: 115.307315ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:38.938684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.880677ms","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-03-24T14:49:38.938781Z","caller":"traceutil/trace.go:171","msg":"trace[780461218] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"300.031081ms","start":"2026-03-24T14:49:38.638727Z","end":"2026-03-24T14:49:38.938758Z","steps":["trace[780461218] 'agreement among raft nodes before linearized reading' (duration: 298.389302ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:38.938847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:38.638706Z","time spent":"300.121884ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-03-24T14:49:45.569555Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9224389223901690786,"retry-timeout":"500ms"} {"level":"info","ts":"2026-03-24T14:49:45.892965Z","caller":"traceutil/trace.go:171","msg":"trace[1168060718] linearizableReadLoop","detail":"{readStateIndex:1006; appliedIndex:1005; }","duration":"823.95907ms","start":"2026-03-24T14:49:45.068971Z","end":"2026-03-24T14:49:45.89293Z","steps":["trace[1168060718] 'read index received' (duration: 823.725075ms)","trace[1168060718] 'applied index is now lower than readState.Index' (duration: 232.835µs)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:45.89307Z","caller":"traceutil/trace.go:171","msg":"trace[1778201697] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"902.7046ms","start":"2026-03-24T14:49:44.990332Z","end":"2026-03-24T14:49:45.893036Z","steps":["trace[1778201697] 'process raft request' (duration: 902.445934ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.893237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"824.185187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-03-24T14:49:45.893237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:44.990289Z","time spent":"902.854814ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:45.893302Z","caller":"traceutil/trace.go:171","msg":"trace[1169640094] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:965; }","duration":"824.265589ms","start":"2026-03-24T14:49:45.069011Z","end":"2026-03-24T14:49:45.893277Z","steps":["trace[1169640094] 'agreement among raft nodes before linearized reading' (duration: 824.093074ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.893364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"822.774292ms","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":"warn","ts":"2026-03-24T14:49:45.893403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.069006Z","time spent":"824.379971ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-03-24T14:49:45.893444Z","caller":"traceutil/trace.go:171","msg":"trace[601626185] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:965; }","duration":"822.878324ms","start":"2026-03-24T14:49:45.07055Z","end":"2026-03-24T14:49:45.893429Z","steps":["trace[601626185] 'agreement among raft nodes before linearized reading' (duration: 822.708ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.893482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.561441ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-03-24T14:49:45.893508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.007229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:49:45.893562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.298493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-03-24T14:49:45.893618Z","caller":"traceutil/trace.go:171","msg":"trace[1674058350] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:965; }","duration":"823.720524ms","start":"2026-03-24T14:49:45.06985Z","end":"2026-03-24T14:49:45.89357Z","steps":["trace[1674058350] 'agreement among raft nodes before linearized reading' (duration: 823.427388ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:45.893645Z","caller":"traceutil/trace.go:171","msg":"trace[272839571] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:965; }","duration":"571.443416ms","start":"2026-03-24T14:49:45.322182Z","end":"2026-03-24T14:49:45.893625Z","steps":["trace[272839571] 'agreement among raft nodes before linearized reading' (duration: 571.238661ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.89369Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.322164Z","time spent":"571.513247ms","remote":"127.0.0.1:54160","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":67,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-03-24T14:49:45.893267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.805718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-03-24T14:49:45.893783Z","caller":"traceutil/trace.go:171","msg":"trace[1906781772] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:965; }","duration":"424.381242ms","start":"2026-03-24T14:49:45.469384Z","end":"2026-03-24T14:49:45.893765Z","steps":["trace[1906781772] 'agreement among raft nodes before linearized reading' (duration: 423.813938ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.8935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.070541Z","time spent":"822.945967ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-03-24T14:49:45.893843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.469362Z","time spent":"424.464205ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"info","ts":"2026-03-24T14:49:45.893651Z","caller":"traceutil/trace.go:171","msg":"trace[408330296] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:965; }","duration":"377.172303ms","start":"2026-03-24T14:49:45.516434Z","end":"2026-03-24T14:49:45.893607Z","steps":["trace[408330296] 'agreement among raft nodes before linearized reading' (duration: 376.939697ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.894836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.516418Z","time spent":"378.384414ms","remote":"127.0.0.1:53852","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-03-24T14:49:45.893726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.069839Z","time spent":"823.871168ms","remote":"127.0.0.1:54100","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":"warn","ts":"2026-03-24T14:49:45.895001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.016422ms","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-03-24T14:49:45.895067Z","caller":"traceutil/trace.go:171","msg":"trace[554155749] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:965; }","duration":"826.117615ms","start":"2026-03-24T14:49:45.068931Z","end":"2026-03-24T14:49:45.895049Z","steps":["trace[554155749] 'agreement among raft nodes before linearized reading' (duration: 824.154637ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:45.895108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.068914Z","time spent":"826.185497ms","remote":"127.0.0.1:54100","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":"warn","ts":"2026-03-24T14:49:46.310054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.808256ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-03-24T14:49:46.310364Z","caller":"traceutil/trace.go:171","msg":"trace[414618544] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"411.490994ms","start":"2026-03-24T14:49:45.898844Z","end":"2026-03-24T14:49:46.310335Z","steps":["trace[414618544] 'process raft request' (duration: 185.306389ms)","trace[414618544] 'compare' (duration: 225.56942ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:46.310441Z","caller":"traceutil/trace.go:171","msg":"trace[1736470650] linearizableReadLoop","detail":"{readStateIndex:1010; appliedIndex:1006; }","duration":"410.717875ms","start":"2026-03-24T14:49:45.899704Z","end":"2026-03-24T14:49:46.310422Z","steps":["trace[1736470650] 'read index received' (duration: 184.53873ms)","trace[1736470650] 'applied index is now lower than readState.Index' (duration: 226.177995ms)"],"step_count":2} {"level":"info","ts":"2026-03-24T14:49:46.310464Z","caller":"traceutil/trace.go:171","msg":"trace[985070468] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"411.106464ms","start":"2026-03-24T14:49:45.899335Z","end":"2026-03-24T14:49:46.310441Z","steps":["trace[985070468] 'process raft request' (duration: 410.895908ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:46.310499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.89882Z","time spent":"411.609737ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:46.310569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.899324Z","time spent":"411.197087ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:46.310561Z","caller":"traceutil/trace.go:171","msg":"trace[761773092] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"411.152375ms","start":"2026-03-24T14:49:45.899338Z","end":"2026-03-24T14:49:46.31049Z","steps":["trace[761773092] 'process raft request' (duration: 410.979201ms)"],"step_count":1} {"level":"info","ts":"2026-03-24T14:49:46.31066Z","caller":"traceutil/trace.go:171","msg":"trace[2121962242] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"411.208347ms","start":"2026-03-24T14:49:45.899435Z","end":"2026-03-24T14:49:46.310643Z","steps":["trace[2121962242] 'process raft request' (duration: 410.936841ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:46.31072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.962591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-03-24T14:49:46.310742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.899322Z","time spent":"411.379442ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-03-24T14:49:46.310761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.899426Z","time spent":"411.284819ms","remote":"127.0.0.1:54100","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-03-24T14:49:46.310769Z","caller":"traceutil/trace.go:171","msg":"trace[1900083359] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:969; }","duration":"411.074354ms","start":"2026-03-24T14:49:45.899682Z","end":"2026-03-24T14:49:46.310757Z","steps":["trace[1900083359] 'agreement among raft nodes before linearized reading' (duration: 410.946551ms)"],"step_count":1} {"level":"warn","ts":"2026-03-24T14:49:46.310923Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-03-24T14:49:45.899675Z","time spent":"411.238238ms","remote":"127.0.0.1:53970","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "}