{"level":"warn","ts":"2026-07-14T16:52:31.651148Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:52:31.651239Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.11: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.11:2380","--initial-cluster=instance=https://199.19.213.11:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.11:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.11:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-14T16:52:31.651308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-14T16:52:31.651323Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-07-14T16:52:31.65134Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:52:31.652206Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"]} {"level":"info","ts":"2026-07-14T16:52:31.652307Z","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.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.11:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-14T16:52:31.661871Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"9.342202ms"} {"level":"info","ts":"2026-07-14T16:52:31.6777Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ce8ad90e35537ee3","cluster-id":"3c17b62c6529d987"} {"level":"info","ts":"2026-07-14T16:52:31.677812Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T16:52:31.677853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 0"} {"level":"info","ts":"2026-07-14T16:52:31.67788Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ce8ad90e35537ee3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T16:52:31.677898Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became follower at term 1"} {"level":"info","ts":"2026-07-14T16:52:31.677971Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"warn","ts":"2026-07-14T16:52:31.688302Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T16:52:31.690002Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T16:52:31.691523Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-14T16:52:31.694405Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ce8ad90e35537ee3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T16:52:31.697867Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ce8ad90e35537ee3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T16:52:31.698304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 switched to configuration voters=(14882946573646659299)"} {"level":"info","ts":"2026-07-14T16:52:31.69845Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","added-peer-id":"ce8ad90e35537ee3","added-peer-peer-urls":["https://199.19.213.11:2380"]} {"level":"info","ts":"2026-07-14T16:52:31.698728Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:31.698856Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:31.698875Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-14T16:52:31.702992Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-14T16:52:31.703102Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-07-14T16:52:31.703181Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.11:2380"} {"level":"info","ts":"2026-07-14T16:52:31.703233Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ce8ad90e35537ee3","initial-advertise-peer-urls":["https://199.19.213.11:2380"],"listen-peer-urls":["https://199.19.213.11:2380"],"advertise-client-urls":["https://199.19.213.11:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.11:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T16:52:31.703275Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T16:52:31.879807Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T16:52:31.879856Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T16:52:31.879872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgPreVoteResp from ce8ad90e35537ee3 at term 1"} {"level":"info","ts":"2026-07-14T16:52:31.879886Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became candidate at term 2"} {"level":"info","ts":"2026-07-14T16:52:31.879893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 received MsgVoteResp from ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-07-14T16:52:31.879905Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ce8ad90e35537ee3 became leader at term 2"} {"level":"info","ts":"2026-07-14T16:52:31.880102Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ce8ad90e35537ee3 elected leader ce8ad90e35537ee3 at term 2"} {"level":"info","ts":"2026-07-14T16:52:31.888078Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ce8ad90e35537ee3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.11:2379]}","request-path":"/0/members/ce8ad90e35537ee3/attributes","cluster-id":"3c17b62c6529d987","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T16:52:31.888094Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:52:31.888098Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:31.888228Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:52:31.88843Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T16:52:31.888509Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T16:52:31.890959Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.11:2379"} {"level":"info","ts":"2026-07-14T16:52:31.892495Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T16:52:31.89348Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"3c17b62c6529d987","local-member-id":"ce8ad90e35537ee3","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:31.893603Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:52:31.893648Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-14T16:52:56.844573Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326909791158946,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:52:57.023769Z","caller":"traceutil/trace.go:171","msg":"trace[2124232796] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"887.604742ms","start":"2026-07-14T16:52:56.136133Z","end":"2026-07-14T16:52:57.023738Z","steps":["trace[2124232796] 'process raft request' (duration: 881.654209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:57.024517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:56.136114Z","time spent":"887.774087ms","remote":"127.0.0.1:50032","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3840,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:52:57.024676Z","caller":"traceutil/trace.go:171","msg":"trace[1028449922] transaction","detail":"{read_only:false; number_of_response:1; response_revision:406; }","duration":"866.541601ms","start":"2026-07-14T16:52:56.158104Z","end":"2026-07-14T16:52:57.024646Z","steps":["trace[1028449922] 'process raft request' (duration: 865.568198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:57.024866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:56.158089Z","time spent":"866.656424ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":55,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-07-14T16:52:57.025093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"681.323533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:52:57.025204Z","caller":"traceutil/trace.go:171","msg":"trace[741800419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"681.472296ms","start":"2026-07-14T16:52:56.343714Z","end":"2026-07-14T16:52:57.025186Z","steps":["trace[741800419] 'agreement among raft nodes before linearized reading' (duration: 681.305832ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:57.025258Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:56.343695Z","time spent":"681.548899ms","remote":"127.0.0.1:53676","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-07-14T16:52:57.025086Z","caller":"traceutil/trace.go:171","msg":"trace[790756542] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:418; }","duration":"680.77895ms","start":"2026-07-14T16:52:56.343765Z","end":"2026-07-14T16:52:57.024544Z","steps":["trace[790756542] 'read index received' (duration: 674.014657ms)","trace[790756542] 'applied index is now lower than readState.Index' (duration: 6.762583ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:52:57.025996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.319272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:52:57.026035Z","caller":"traceutil/trace.go:171","msg":"trace[2019525535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:406; }","duration":"445.396014ms","start":"2026-07-14T16:52:56.580629Z","end":"2026-07-14T16:52:57.026025Z","steps":["trace[2019525535] 'agreement among raft nodes before linearized reading' (duration: 445.317262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:57.026068Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:56.580608Z","time spent":"445.447185ms","remote":"127.0.0.1:53920","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-07-14T16:52:57.839042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.091985ms","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-07-14T16:52:57.839117Z","caller":"traceutil/trace.go:171","msg":"trace[712862842] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:422; }","duration":"140.210028ms","start":"2026-07-14T16:52:57.69889Z","end":"2026-07-14T16:52:57.8391Z","steps":["trace[712862842] 'range keys from in-memory index tree' (duration: 139.995403ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:07.497595Z","caller":"traceutil/trace.go:171","msg":"trace[1828805868] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"114.292565ms","start":"2026-07-14T16:53:07.383268Z","end":"2026-07-14T16:53:07.49756Z","steps":["trace[1828805868] 'process raft request' (duration: 113.816014ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.033003Z","caller":"traceutil/trace.go:171","msg":"trace[985801304] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:476; }","duration":"134.550458ms","start":"2026-07-14T16:53:07.898429Z","end":"2026-07-14T16:53:08.03298Z","steps":["trace[985801304] 'read index received' (duration: 34.787741ms)","trace[985801304] 'applied index is now lower than readState.Index' (duration: 99.761497ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:08.033158Z","caller":"traceutil/trace.go:171","msg":"trace[1434101804] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"184.349507ms","start":"2026-07-14T16:53:07.848699Z","end":"2026-07-14T16:53:08.033049Z","steps":["trace[1434101804] 'process raft request' (duration: 184.216174ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.033187Z","caller":"traceutil/trace.go:171","msg":"trace[1708456891] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"185.033443ms","start":"2026-07-14T16:53:07.848095Z","end":"2026-07-14T16:53:08.033128Z","steps":["trace[1708456891] 'process raft request' (duration: 85.538402ms)","trace[1708456891] 'compare' (duration: 99.062721ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:08.033422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.970989ms","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-07-14T16:53:08.033473Z","caller":"traceutil/trace.go:171","msg":"trace[1988978878] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"135.06761ms","start":"2026-07-14T16:53:07.898391Z","end":"2026-07-14T16:53:08.033458Z","steps":["trace[1988978878] 'agreement among raft nodes before linearized reading' (duration: 134.707512ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.033734Z","caller":"traceutil/trace.go:171","msg":"trace[449199189] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"184.814838ms","start":"2026-07-14T16:53:07.848177Z","end":"2026-07-14T16:53:08.032991Z","steps":["trace[449199189] 'process raft request' (duration: 184.698665ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.056257Z","caller":"traceutil/trace.go:171","msg":"trace[329009812] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"152.461039ms","start":"2026-07-14T16:53:07.903775Z","end":"2026-07-14T16:53:08.056236Z","steps":["trace[329009812] 'process raft request' (duration: 152.136692ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.2396Z","caller":"traceutil/trace.go:171","msg":"trace[143233137] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:489; }","duration":"131.056828ms","start":"2026-07-14T16:53:08.10852Z","end":"2026-07-14T16:53:08.239577Z","steps":["trace[143233137] 'read index received' (duration: 101.034259ms)","trace[143233137] 'applied index is now lower than readState.Index' (duration: 30.021699ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:08.239712Z","caller":"traceutil/trace.go:171","msg":"trace[1242217618] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"133.878003ms","start":"2026-07-14T16:53:08.105817Z","end":"2026-07-14T16:53:08.239695Z","steps":["trace[1242217618] 'process raft request' (duration: 133.702299ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:08.239818Z","caller":"traceutil/trace.go:171","msg":"trace[180277502] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"134.067237ms","start":"2026-07-14T16:53:08.105732Z","end":"2026-07-14T16:53:08.239799Z","steps":["trace[180277502] 'process raft request' (duration: 103.811633ms)","trace[180277502] 'compare' (duration: 29.716422ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:08.240202Z","caller":"traceutil/trace.go:171","msg":"trace[1519786293] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"131.518609ms","start":"2026-07-14T16:53:08.10865Z","end":"2026-07-14T16:53:08.240169Z","steps":["trace[1519786293] 'process raft request' (duration: 131.357875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:08.24028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.58589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/cilium-operator-869df985b8\" ","response":"range_response_count:1 size:4309"} {"level":"info","ts":"2026-07-14T16:53:08.240432Z","caller":"traceutil/trace.go:171","msg":"trace[285197218] range","detail":"{range_begin:/registry/replicasets/kube-system/cilium-operator-869df985b8; range_end:; response_count:1; response_revision:475; }","duration":"131.866086ms","start":"2026-07-14T16:53:08.108507Z","end":"2026-07-14T16:53:08.240373Z","steps":["trace[285197218] 'agreement among raft nodes before linearized reading' (duration: 131.396046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:08.240515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.484405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329956"} {"level":"info","ts":"2026-07-14T16:53:08.240575Z","caller":"traceutil/trace.go:171","msg":"trace[1468053854] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:476; }","duration":"113.566217ms","start":"2026-07-14T16:53:08.126992Z","end":"2026-07-14T16:53:08.240558Z","steps":["trace[1468053854] 'agreement among raft nodes before linearized reading' (duration: 113.109616ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:10.31076Z","caller":"traceutil/trace.go:171","msg":"trace[86408721] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"577.461406ms","start":"2026-07-14T16:53:09.733278Z","end":"2026-07-14T16:53:10.310739Z","steps":["trace[86408721] 'process raft request' (duration: 577.415245ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:10.310751Z","caller":"traceutil/trace.go:171","msg":"trace[1483591309] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"594.188788ms","start":"2026-07-14T16:53:09.716516Z","end":"2026-07-14T16:53:10.310705Z","steps":["trace[1483591309] 'process raft request' (duration: 593.757009ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:10.310877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.733265Z","time spent":"577.553658ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:10.310952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.716482Z","time spent":"594.383092ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T16:53:10.310994Z","caller":"traceutil/trace.go:171","msg":"trace[25555120] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"580.25203ms","start":"2026-07-14T16:53:09.730735Z","end":"2026-07-14T16:53:10.310987Z","steps":["trace[25555120] 'process raft request' (duration: 579.874041ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:10.310981Z","caller":"traceutil/trace.go:171","msg":"trace[928055664] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"587.798122ms","start":"2026-07-14T16:53:09.723145Z","end":"2026-07-14T16:53:10.310943Z","steps":["trace[928055664] 'process raft request' (duration: 587.394833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:10.311031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.730707Z","time spent":"580.3048ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:10.311175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.723125Z","time spent":"587.947586ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:10.315809Z","caller":"traceutil/trace.go:171","msg":"trace[2053262750] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:522; }","duration":"215.315679ms","start":"2026-07-14T16:53:10.100477Z","end":"2026-07-14T16:53:10.315792Z","steps":["trace[2053262750] 'read index received' (duration: 209.950827ms)","trace[2053262750] 'applied index is now lower than readState.Index' (duration: 5.364032ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:10.315947Z","caller":"traceutil/trace.go:171","msg":"trace[103246837] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"574.756923ms","start":"2026-07-14T16:53:09.741179Z","end":"2026-07-14T16:53:10.315936Z","steps":["trace[103246837] 'process raft request' (duration: 574.338004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:10.316017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.741168Z","time spent":"574.802864ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:10.316265Z","caller":"traceutil/trace.go:171","msg":"trace[1864338774] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"466.825757ms","start":"2026-07-14T16:53:09.849429Z","end":"2026-07-14T16:53:10.316255Z","steps":["trace[1864338774] 'process raft request' (duration: 466.213523ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:10.316316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:09.849403Z","time spent":"466.887698ms","remote":"127.0.0.1:53728","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:10.316329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.11786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T16:53:10.316392Z","caller":"traceutil/trace.go:171","msg":"trace[2055376084] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:509; }","duration":"206.223292ms","start":"2026-07-14T16:53:10.11015Z","end":"2026-07-14T16:53:10.316374Z","steps":["trace[2055376084] 'agreement among raft nodes before linearized reading' (duration: 206.036698ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:10.31654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.068076ms","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-07-14T16:53:10.316568Z","caller":"traceutil/trace.go:171","msg":"trace[1558568357] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:509; }","duration":"216.131947ms","start":"2026-07-14T16:53:10.100427Z","end":"2026-07-14T16:53:10.316559Z","steps":["trace[1558568357] 'agreement among raft nodes before linearized reading' (duration: 216.052796ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:14.040934Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9143326909791159314,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T16:53:14.462709Z","caller":"traceutil/trace.go:171","msg":"trace[418412590] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:589; }","duration":"922.712808ms","start":"2026-07-14T16:53:13.539967Z","end":"2026-07-14T16:53:14.46268Z","steps":["trace[418412590] 'read index received' (duration: 922.480943ms)","trace[418412590] 'applied index is now lower than readState.Index' (duration: 230.215µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:14.462736Z","caller":"traceutil/trace.go:171","msg":"trace[1871205805] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"994.96438ms","start":"2026-07-14T16:53:13.467746Z","end":"2026-07-14T16:53:14.462711Z","steps":["trace[1871205805] 'process raft request' (duration: 994.590182ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:14.462908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:13.467727Z","time spent":"995.117574ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036023,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T16:53:14.462992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.008304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-14T16:53:14.463053Z","caller":"traceutil/trace.go:171","msg":"trace[1857011259] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"923.104997ms","start":"2026-07-14T16:53:13.53993Z","end":"2026-07-14T16:53:14.463035Z","steps":["trace[1857011259] 'agreement among raft nodes before linearized reading' (duration: 922.880472ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:14.463093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:13.539914Z","time spent":"923.171908ms","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:53:14.642646Z","caller":"traceutil/trace.go:171","msg":"trace[1756867336] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"176.038498ms","start":"2026-07-14T16:53:14.466582Z","end":"2026-07-14T16:53:14.642621Z","steps":["trace[1756867336] 'process raft request' (duration: 175.928185ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:14.642836Z","caller":"traceutil/trace.go:171","msg":"trace[2145050994] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"253.873199ms","start":"2026-07-14T16:53:14.388929Z","end":"2026-07-14T16:53:14.642802Z","steps":["trace[2145050994] 'process raft request' (duration: 249.255585ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:14.642919Z","caller":"traceutil/trace.go:171","msg":"trace[173885415] linearizableReadLoop","detail":"{readStateIndex:591; appliedIndex:590; }","duration":"179.961886ms","start":"2026-07-14T16:53:14.462787Z","end":"2026-07-14T16:53:14.642749Z","steps":["trace[173885415] 'read index received' (duration: 175.412014ms)","trace[173885415] 'applied index is now lower than readState.Index' (duration: 4.547372ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:14.643032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.453151ms","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-07-14T16:53:14.643074Z","caller":"traceutil/trace.go:171","msg":"trace[248948258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:572; }","duration":"296.543794ms","start":"2026-07-14T16:53:14.346518Z","end":"2026-07-14T16:53:14.643061Z","steps":["trace[248948258] 'agreement among raft nodes before linearized reading' (duration: 296.39562ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:14.643193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.265893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:53:14.643184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.382227ms","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-07-14T16:53:14.643434Z","caller":"traceutil/trace.go:171","msg":"trace[1969234018] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:572; }","duration":"286.55627ms","start":"2026-07-14T16:53:14.356772Z","end":"2026-07-14T16:53:14.643328Z","steps":["trace[1969234018] 'agreement among raft nodes before linearized reading' (duration: 286.041059ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:14.643247Z","caller":"traceutil/trace.go:171","msg":"trace[1690557894] range","detail":"{range_begin:/registry/serviceaccounts/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:572; }","duration":"121.383906ms","start":"2026-07-14T16:53:14.52185Z","end":"2026-07-14T16:53:14.643233Z","steps":["trace[1690557894] 'agreement among raft nodes before linearized reading' (duration: 121.262274ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:18.623389Z","caller":"traceutil/trace.go:171","msg":"trace[554778631] transaction","detail":"{read_only:false; response_revision:596; number_of_response:1; }","duration":"686.357167ms","start":"2026-07-14T16:53:17.937018Z","end":"2026-07-14T16:53:18.623375Z","steps":["trace[554778631] 'process raft request' (duration: 685.421686ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:18.6236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:17.936667Z","time spent":"686.809947ms","remote":"127.0.0.1:53758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:18.62387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.15881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-s6b77\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-14T16:53:18.623969Z","caller":"traceutil/trace.go:171","msg":"trace[219800458] range","detail":"{range_begin:/registry/pods/kube-system/cilium-s6b77; range_end:; response_count:1; response_revision:596; }","duration":"439.374994ms","start":"2026-07-14T16:53:18.184569Z","end":"2026-07-14T16:53:18.623943Z","steps":["trace[219800458] 'agreement among raft nodes before linearized reading' (duration: 438.946485ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:18.62331Z","caller":"traceutil/trace.go:171","msg":"trace[1303069768] linearizableReadLoop","detail":"{readStateIndex:617; appliedIndex:616; }","duration":"438.625698ms","start":"2026-07-14T16:53:18.184649Z","end":"2026-07-14T16:53:18.623274Z","steps":["trace[1303069768] 'read index received' (duration: 437.463052ms)","trace[1303069768] 'applied index is now lower than readState.Index' (duration: 1.141445ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:18.624015Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.184539Z","time spent":"439.465936ms","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-s6b77\" "} {"level":"warn","ts":"2026-07-14T16:53:19.321475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"604.251536ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:19.321587Z","caller":"traceutil/trace.go:171","msg":"trace[701864097] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:617; }","duration":"697.367497ms","start":"2026-07-14T16:53:18.624201Z","end":"2026-07-14T16:53:19.321568Z","steps":["trace[701864097] 'read index received' (duration: 92.891135ms)","trace[701864097] 'applied index is now lower than readState.Index' (duration: 604.474812ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:19.321677Z","caller":"traceutil/trace.go:171","msg":"trace[609222358] transaction","detail":"{read_only:false; response_revision:597; number_of_response:1; }","duration":"1.066810712s","start":"2026-07-14T16:53:18.254851Z","end":"2026-07-14T16:53:19.321662Z","steps":["trace[609222358] 'process raft request' (duration: 462.140816ms)","trace[609222358] 'compare' (duration: 604.016751ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:19.321749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.254816Z","time spent":"1.066899523s","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:19.322069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.569734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:19.32217Z","caller":"traceutil/trace.go:171","msg":"trace[1505889914] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:597; }","duration":"976.677517ms","start":"2026-07-14T16:53:18.345473Z","end":"2026-07-14T16:53:19.322151Z","steps":["trace[1505889914] 'agreement among raft nodes before linearized reading' (duration: 976.500663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:19.322262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.34541Z","time spent":"976.84128ms","remote":"127.0.0.1:53676","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-07-14T16:53:20.983448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.978649ms","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-07-14T16:53:20.983526Z","caller":"traceutil/trace.go:171","msg":"trace[1401204200] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:597; }","duration":"130.071301ms","start":"2026-07-14T16:53:20.853443Z","end":"2026-07-14T16:53:20.983514Z","steps":["trace[1401204200] 'range keys from in-memory index tree' (duration: 129.967039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:20.983623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.546507116s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:20.98414Z","caller":"traceutil/trace.go:171","msg":"trace[2041754528] linearizableReadLoop","detail":"{readStateIndex:620; appliedIndex:618; }","duration":"1.66250334s","start":"2026-07-14T16:53:19.321622Z","end":"2026-07-14T16:53:20.984125Z","steps":["trace[2041754528] 'read index received' (duration: 115.32994ms)","trace[2041754528] 'applied index is now lower than readState.Index' (duration: 1.54717262s)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:20.984223Z","caller":"traceutil/trace.go:171","msg":"trace[258944427] transaction","detail":"{read_only:false; response_revision:598; number_of_response:1; }","duration":"2.338091268s","start":"2026-07-14T16:53:18.646116Z","end":"2026-07-14T16:53:20.984207Z","steps":["trace[258944427] 'process raft request' (duration: 790.921367ms)","trace[258944427] 'compare' (duration: 1.546346963s)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:20.98427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.646088Z","time spent":"2.338155348s","remote":"127.0.0.1:53840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:20.984362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.321258327s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T16:53:20.984369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.314197012s","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-07-14T16:53:20.984394Z","caller":"traceutil/trace.go:171","msg":"trace[1344792525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:599; }","duration":"2.314259633s","start":"2026-07-14T16:53:18.670129Z","end":"2026-07-14T16:53:20.984388Z","steps":["trace[1344792525] 'agreement among raft nodes before linearized reading' (duration: 2.31416298s)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:20.984397Z","caller":"traceutil/trace.go:171","msg":"trace[1481416624] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:599; }","duration":"2.321327398s","start":"2026-07-14T16:53:18.663058Z","end":"2026-07-14T16:53:20.984386Z","steps":["trace[1481416624] 'agreement among raft nodes before linearized reading' (duration: 2.321195426s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:20.984413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.670119Z","time spent":"2.314289253s","remote":"127.0.0.1:53920","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-07-14T16:53:20.984436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.718748543s","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-07-14T16:53:20.984438Z","caller":"traceutil/trace.go:171","msg":"trace[166418654] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"2.311925772s","start":"2026-07-14T16:53:18.672464Z","end":"2026-07-14T16:53:20.98439Z","steps":["trace[166418654] 'process raft request' (duration: 2.311591924s)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:20.984508Z","caller":"traceutil/trace.go:171","msg":"trace[31202038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"1.718873096s","start":"2026-07-14T16:53:19.265621Z","end":"2026-07-14T16:53:20.984494Z","steps":["trace[31202038] 'agreement among raft nodes before linearized reading' (duration: 1.718636981s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:20.984419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.663034Z","time spent":"2.321378699s","remote":"127.0.0.1:53920","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-07-14T16:53:20.984552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:19.265603Z","time spent":"1.718940638s","remote":"127.0.0.1:53920","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-07-14T16:53:20.984558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.329045718s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T16:53:20.984576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.672445Z","time spent":"2.312067634s","remote":"127.0.0.1:53892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T16:53:20.984586Z","caller":"traceutil/trace.go:171","msg":"trace[956316614] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:599; }","duration":"2.329172651s","start":"2026-07-14T16:53:18.655404Z","end":"2026-07-14T16:53:20.984577Z","steps":["trace[956316614] 'agreement among raft nodes before linearized reading' (duration: 2.329005457s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:20.984618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:18.65539Z","time spent":"2.329221491s","remote":"127.0.0.1:53920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-14T16:53:21.086784Z","caller":"traceutil/trace.go:171","msg":"trace[29484093] linearizableReadLoop","detail":"{readStateIndex:621; appliedIndex:620; }","duration":"102.585554ms","start":"2026-07-14T16:53:20.984167Z","end":"2026-07-14T16:53:21.086753Z","steps":["trace[29484093] 'read index received' (duration: 60.750563ms)","trace[29484093] 'applied index is now lower than readState.Index' (duration: 41.830841ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:21.086923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.763563931s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:21.087239Z","caller":"traceutil/trace.go:171","msg":"trace[267379073] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:599; }","duration":"1.76397372s","start":"2026-07-14T16:53:19.323242Z","end":"2026-07-14T16:53:21.087215Z","steps":["trace[267379073] 'agreement among raft nodes before linearized reading' (duration: 1.763594842s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:21.087757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:19.323228Z","time spent":"1.76442828s","remote":"127.0.0.1:53686","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-07-14T16:53:22.688334Z","caller":"traceutil/trace.go:171","msg":"trace[1424469362] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"204.071745ms","start":"2026-07-14T16:53:22.48424Z","end":"2026-07-14T16:53:22.688312Z","steps":["trace[1424469362] 'process raft request' (duration: 203.969533ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:33.281981Z","caller":"traceutil/trace.go:171","msg":"trace[530770353] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"123.574058ms","start":"2026-07-14T16:53:33.15838Z","end":"2026-07-14T16:53:33.281954Z","steps":["trace[530770353] 'read index received' (duration: 123.426055ms)","trace[530770353] 'applied index is now lower than readState.Index' (duration: 147.093µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:33.282158Z","caller":"traceutil/trace.go:171","msg":"trace[134741558] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"129.566823ms","start":"2026-07-14T16:53:33.152539Z","end":"2026-07-14T16:53:33.282105Z","steps":["trace[134741558] 'process raft request' (duration: 129.184145ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.282284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.882895ms","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-07-14T16:53:33.282327Z","caller":"traceutil/trace.go:171","msg":"trace[1910541168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"123.978057ms","start":"2026-07-14T16:53:33.158336Z","end":"2026-07-14T16:53:33.282314Z","steps":["trace[1910541168] 'agreement among raft nodes before linearized reading' (duration: 123.761762ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.282507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.673469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-14T16:53:33.282615Z","caller":"traceutil/trace.go:171","msg":"trace[1620356092] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"122.927644ms","start":"2026-07-14T16:53:33.159665Z","end":"2026-07-14T16:53:33.282593Z","steps":["trace[1620356092] 'agreement among raft nodes before linearized reading' (duration: 122.513186ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:33.283305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.608198ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-07-14T16:53:33.283404Z","caller":"traceutil/trace.go:171","msg":"trace[988643755] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"122.775042ms","start":"2026-07-14T16:53:33.160609Z","end":"2026-07-14T16:53:33.283384Z","steps":["trace[988643755] 'agreement among raft nodes before linearized reading' (duration: 121.692019ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:36.76723Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.185535797s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T16:53:36.978445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.640683ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:53:36.978568Z","caller":"traceutil/trace.go:171","msg":"trace[959731702] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"1.396956016s","start":"2026-07-14T16:53:35.581591Z","end":"2026-07-14T16:53:36.978547Z","steps":["trace[959731702] 'process raft request' (duration: 1.186105978s)","trace[959731702] 'compare' (duration: 210.49141ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:36.978636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:35.581572Z","time spent":"1.397029827s","remote":"127.0.0.1:53706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:36.980212Z","caller":"traceutil/trace.go:171","msg":"trace[1821499029] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:787; }","duration":"625.67378ms","start":"2026-07-14T16:53:36.354512Z","end":"2026-07-14T16:53:36.980186Z","steps":["trace[1821499029] 'read index received' (duration: 413.049796ms)","trace[1821499029] 'applied index is now lower than readState.Index' (duration: 212.622354ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:36.980493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.966316ms","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-07-14T16:53:36.980513Z","caller":"traceutil/trace.go:171","msg":"trace[606096628] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"649.001132ms","start":"2026-07-14T16:53:36.331464Z","end":"2026-07-14T16:53:36.980465Z","steps":["trace[606096628] 'process raft request' (duration: 648.597443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:36.980746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:36.331438Z","time spent":"649.189046ms","remote":"127.0.0.1:53892","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:53:36.980576Z","caller":"traceutil/trace.go:171","msg":"trace[1639352023] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:763; }","duration":"626.090589ms","start":"2026-07-14T16:53:36.35447Z","end":"2026-07-14T16:53:36.980561Z","steps":["trace[1639352023] 'agreement among raft nodes before linearized reading' (duration: 625.871064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:36.980967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:36.354458Z","time spent":"626.491987ms","remote":"127.0.0.1:53920","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":"info","ts":"2026-07-14T16:53:51.633519Z","caller":"traceutil/trace.go:171","msg":"trace[67278305] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"159.530868ms","start":"2026-07-14T16:53:51.473968Z","end":"2026-07-14T16:53:51.633499Z","steps":["trace[67278305] 'process raft request' (duration: 159.033629ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:51.635078Z","caller":"traceutil/trace.go:171","msg":"trace[487302584] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"160.544929ms","start":"2026-07-14T16:53:51.474521Z","end":"2026-07-14T16:53:51.635066Z","steps":["trace[487302584] 'process raft request' (duration: 159.982967ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:16.34918Z","caller":"traceutil/trace.go:171","msg":"trace[1697572948] transaction","detail":"{read_only:false; response_revision:1224; number_of_response:1; }","duration":"247.082267ms","start":"2026-07-14T16:55:16.102071Z","end":"2026-07-14T16:55:16.349153Z","steps":["trace[1697572948] 'process raft request' (duration: 247.001566ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:16.349437Z","caller":"traceutil/trace.go:171","msg":"trace[539747102] transaction","detail":"{read_only:false; response_revision:1223; number_of_response:1; }","duration":"247.893821ms","start":"2026-07-14T16:55:16.10151Z","end":"2026-07-14T16:55:16.349404Z","steps":["trace[539747102] 'process raft request' (duration: 214.517811ms)","trace[539747102] 'compare' (duration: 32.75674ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:26.446919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.532054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:26.447008Z","caller":"traceutil/trace.go:171","msg":"trace[1395838873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"101.670646ms","start":"2026-07-14T16:55:26.345314Z","end":"2026-07-14T16:55:26.446985Z","steps":["trace[1395838873] 'range keys from in-memory index tree' (duration: 101.459673ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:34.196516Z","caller":"traceutil/trace.go:171","msg":"trace[1113637864] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"143.230941ms","start":"2026-07-14T16:55:34.053267Z","end":"2026-07-14T16:55:34.196498Z","steps":["trace[1113637864] 'process raft request' (duration: 143.096799ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:34.796317Z","caller":"traceutil/trace.go:171","msg":"trace[1053707080] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1372; }","duration":"119.023886ms","start":"2026-07-14T16:55:34.677269Z","end":"2026-07-14T16:55:34.796293Z","steps":["trace[1053707080] 'read index received' (duration: 118.723981ms)","trace[1053707080] 'applied index is now lower than readState.Index' (duration: 298.895µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:55:34.796459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.179649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:55:34.796496Z","caller":"traceutil/trace.go:171","msg":"trace[916463419] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1321; }","duration":"119.269361ms","start":"2026-07-14T16:55:34.677218Z","end":"2026-07-14T16:55:34.796487Z","steps":["trace[916463419] 'agreement among raft nodes before linearized reading' (duration: 119.162459ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:55:34.796652Z","caller":"traceutil/trace.go:171","msg":"trace[1548038293] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"214.038719ms","start":"2026-07-14T16:55:34.582587Z","end":"2026-07-14T16:55:34.796625Z","steps":["trace[1548038293] 'process raft request' (duration: 213.414368ms)"],"step_count":1}