{"level":"warn","ts":"2026-05-21T19:31:41.322036Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:31:41.32216Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.180: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.180:2380","--initial-cluster=instance=https://199.19.213.180:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.180:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.180: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-05-21T19:31:41.322328Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-21T19:31:41.322347Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-05-21T19:31:41.322397Z","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-05-21T19:31:41.323259Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"]} {"level":"info","ts":"2026-05-21T19:31:41.323404Z","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.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.180: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-05-21T19:31:41.328874Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.326776ms"} {"level":"info","ts":"2026-05-21T19:31:41.339988Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d6864bfaf2cb565","cluster-id":"32ff95319ee9cd99"} {"level":"info","ts":"2026-05-21T19:31:41.340076Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=()"} {"level":"info","ts":"2026-05-21T19:31:41.340105Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 0"} {"level":"info","ts":"2026-05-21T19:31:41.34012Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5d6864bfaf2cb565 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-21T19:31:41.340132Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 1"} {"level":"info","ts":"2026-05-21T19:31:41.340176Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"warn","ts":"2026-05-21T19:31:41.352811Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-21T19:31:41.354586Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-21T19:31:41.356273Z","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-05-21T19:31:41.36099Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5d6864bfaf2cb565","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-21T19:31:41.361124Z","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-05-21T19:31:41.361328Z","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-05-21T19:31:41.361341Z","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-05-21T19:31:41.361214Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5d6864bfaf2cb565","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-21T19:31:41.366557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"info","ts":"2026-05-21T19:31:41.366639Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","added-peer-id":"5d6864bfaf2cb565","added-peer-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-05-21T19:31:41.370169Z","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-05-21T19:31:41.370279Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-05-21T19:31:41.370316Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-05-21T19:31:41.370498Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5d6864bfaf2cb565","initial-advertise-peer-urls":["https://199.19.213.180:2380"],"listen-peer-urls":["https://199.19.213.180:2380"],"advertise-client-urls":["https://199.19.213.180:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.180:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-21T19:31:41.370542Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-21T19:31:42.041409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 is starting a new election at term 1"} {"level":"info","ts":"2026-05-21T19:31:42.041485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-21T19:31:42.041534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgPreVoteResp from 5d6864bfaf2cb565 at term 1"} {"level":"info","ts":"2026-05-21T19:31:42.041566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became candidate at term 2"} {"level":"info","ts":"2026-05-21T19:31:42.041582Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgVoteResp from 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-05-21T19:31:42.041636Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became leader at term 2"} {"level":"info","ts":"2026-05-21T19:31:42.041655Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d6864bfaf2cb565 elected leader 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-05-21T19:31:42.042765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:31:42.043692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5d6864bfaf2cb565","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.180:2379]}","request-path":"/0/members/5d6864bfaf2cb565/attributes","cluster-id":"32ff95319ee9cd99","publish-timeout":"7s"} {"level":"info","ts":"2026-05-21T19:31:42.043709Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:31:42.043746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-21T19:31:42.04415Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-21T19:31:42.04417Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-21T19:31:42.049719Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"32ff95319ee9cd99","local-member-id":"5d6864bfaf2cb565","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:31:42.049839Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:31:42.049878Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-21T19:31:42.049934Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-21T19:31:42.051829Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.180:2379"} {"level":"info","ts":"2026-05-21T19:31:43.77938Z","caller":"traceutil/trace.go:171","msg":"trace[1287249614] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5; }","duration":"259.361397ms","start":"2026-05-21T19:31:43.519976Z","end":"2026-05-21T19:31:43.779338Z","steps":["trace[1287249614] 'process raft request' (duration: 259.252575ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.77949Z","caller":"traceutil/trace.go:171","msg":"trace[496186903] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"250.537891ms","start":"2026-05-21T19:31:43.528929Z","end":"2026-05-21T19:31:43.779467Z","steps":["trace[496186903] 'process raft request' (duration: 250.50046ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.779612Z","caller":"traceutil/trace.go:171","msg":"trace[1636339632] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"254.98636ms","start":"2026-05-21T19:31:43.524608Z","end":"2026-05-21T19:31:43.779595Z","steps":["trace[1636339632] 'process raft request' (duration: 254.732034ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.779876Z","caller":"traceutil/trace.go:171","msg":"trace[1088949330] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"255.336767ms","start":"2026-05-21T19:31:43.524526Z","end":"2026-05-21T19:31:43.779863Z","steps":["trace[1088949330] 'process raft request' (duration: 254.734724ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.779901Z","caller":"traceutil/trace.go:171","msg":"trace[2036488260] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"255.282746ms","start":"2026-05-21T19:31:43.524591Z","end":"2026-05-21T19:31:43.779874Z","steps":["trace[2036488260] 'process raft request' (duration: 254.772885ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.77941Z","caller":"traceutil/trace.go:171","msg":"trace[2033354675] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"259.573392ms","start":"2026-05-21T19:31:43.519806Z","end":"2026-05-21T19:31:43.779379Z","steps":["trace[2033354675] 'process raft request' (duration: 259.302616ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.779987Z","caller":"traceutil/trace.go:171","msg":"trace[826760579] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"255.333397ms","start":"2026-05-21T19:31:43.524645Z","end":"2026-05-21T19:31:43.779978Z","steps":["trace[826760579] 'process raft request' (duration: 254.753424ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.779999Z","caller":"traceutil/trace.go:171","msg":"trace[383120001] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"255.386789ms","start":"2026-05-21T19:31:43.524573Z","end":"2026-05-21T19:31:43.77996Z","steps":["trace[383120001] 'process raft request' (duration: 254.736754ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.780107Z","caller":"traceutil/trace.go:171","msg":"trace[1042595426] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:15; }","duration":"211.780139ms","start":"2026-05-21T19:31:43.568317Z","end":"2026-05-21T19:31:43.780097Z","steps":["trace[1042595426] 'read index received' (duration: 211.776179ms)","trace[1042595426] 'applied index is now lower than readState.Index' (duration: 3.44µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:31:43.780273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.912641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-05-21T19:31:43.780329Z","caller":"traceutil/trace.go:171","msg":"trace[1054149860] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:11; }","duration":"212.051024ms","start":"2026-05-21T19:31:43.568268Z","end":"2026-05-21T19:31:43.780319Z","steps":["trace[1054149860] 'agreement among raft nodes before linearized reading' (duration: 211.901281ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:31:43.784623Z","caller":"traceutil/trace.go:171","msg":"trace[2144477082] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"212.69172ms","start":"2026-05-21T19:31:43.571879Z","end":"2026-05-21T19:31:43.784571Z","steps":["trace[2144477082] 'process raft request' (duration: 212.545586ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:31:43.785013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.201454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-21T19:31:43.785103Z","caller":"traceutil/trace.go:171","msg":"trace[1107546337] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:12; }","duration":"201.46507ms","start":"2026-05-21T19:31:43.583619Z","end":"2026-05-21T19:31:43.785084Z","steps":["trace[1107546337] 'agreement among raft nodes before linearized reading' (duration: 201.221195ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:04.466959Z","caller":"traceutil/trace.go:171","msg":"trace[849086460] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"111.423493ms","start":"2026-05-21T19:32:04.355513Z","end":"2026-05-21T19:32:04.466937Z","steps":["trace[849086460] 'process raft request' (duration: 111.27263ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.034886Z","caller":"traceutil/trace.go:171","msg":"trace[624471719] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"439.663739ms","start":"2026-05-21T19:32:19.595199Z","end":"2026-05-21T19:32:20.034863Z","steps":["trace[624471719] 'process raft request' (duration: 439.500525ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.03549Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.595175Z","time spent":"439.775191ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.042557Z","caller":"traceutil/trace.go:171","msg":"trace[940880836] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"444.689442ms","start":"2026-05-21T19:32:19.597841Z","end":"2026-05-21T19:32:20.04253Z","steps":["trace[940880836] 'process raft request' (duration: 444.569519ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.042644Z","caller":"traceutil/trace.go:171","msg":"trace[1516016836] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"437.300745ms","start":"2026-05-21T19:32:19.605333Z","end":"2026-05-21T19:32:20.042634Z","steps":["trace[1516016836] 'process raft request' (duration: 437.120301ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.042551Z","caller":"traceutil/trace.go:171","msg":"trace[761681905] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"444.761493ms","start":"2026-05-21T19:32:19.597755Z","end":"2026-05-21T19:32:20.042516Z","steps":["trace[761681905] 'process raft request' (duration: 444.521398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.042782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.605315Z","time spent":"437.400517ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:32:20.042836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.597831Z","time spent":"444.810554ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.042885Z","caller":"traceutil/trace.go:171","msg":"trace[960330339] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"422.270166ms","start":"2026-05-21T19:32:19.620608Z","end":"2026-05-21T19:32:20.042878Z","steps":["trace[960330339] 'process raft request' (duration: 422.056192ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.042899Z","caller":"traceutil/trace.go:171","msg":"trace[1689436759] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"420.896565ms","start":"2026-05-21T19:32:19.621994Z","end":"2026-05-21T19:32:20.04289Z","steps":["trace[1689436759] 'process raft request' (duration: 420.809493ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.04293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.620588Z","time spent":"422.321097ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:32:20.042945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.621988Z","time spent":"420.932625ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.043018Z","caller":"traceutil/trace.go:171","msg":"trace[1029705389] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"413.692793ms","start":"2026-05-21T19:32:19.629284Z","end":"2026-05-21T19:32:20.042977Z","steps":["trace[1029705389] 'process raft request' (duration: 413.55975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.043032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.59773Z","time spent":"445.124952ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.042739Z","caller":"traceutil/trace.go:171","msg":"trace[550828742] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"433.464308ms","start":"2026-05-21T19:32:19.60926Z","end":"2026-05-21T19:32:20.042724Z","steps":["trace[550828742] 'process raft request' (duration: 433.232833ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.043178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.629258Z","time spent":"413.846236ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:32:20.043208Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.609242Z","time spent":"433.949569ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.043227Z","caller":"traceutil/trace.go:171","msg":"trace[1443663232] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"395.762268ms","start":"2026-05-21T19:32:19.647452Z","end":"2026-05-21T19:32:20.043214Z","steps":["trace[1443663232] 'process raft request' (duration: 395.482602ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.043237Z","caller":"traceutil/trace.go:171","msg":"trace[835666607] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:544; }","duration":"222.147651ms","start":"2026-05-21T19:32:19.821071Z","end":"2026-05-21T19:32:20.043219Z","steps":["trace[835666607] 'read index received' (duration: 213.66097ms)","trace[835666607] 'applied index is now lower than readState.Index' (duration: 8.485861ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:32:20.043273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.647446Z","time spent":"395.808329ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.043265Z","caller":"traceutil/trace.go:171","msg":"trace[1889199144] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"379.48705ms","start":"2026-05-21T19:32:19.663773Z","end":"2026-05-21T19:32:20.04326Z","steps":["trace[1889199144] 'process raft request' (duration: 379.216154ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.043274Z","caller":"traceutil/trace.go:171","msg":"trace[2080385655] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"395.930601ms","start":"2026-05-21T19:32:19.647284Z","end":"2026-05-21T19:32:20.043215Z","steps":["trace[2080385655] 'process raft request' (duration: 395.601024ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:20.043321Z","caller":"traceutil/trace.go:171","msg":"trace[919664190] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"253.209261ms","start":"2026-05-21T19:32:19.790103Z","end":"2026-05-21T19:32:20.043312Z","steps":["trace[919664190] 'process raft request' (duration: 253.045097ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.043332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.663742Z","time spent":"379.577252ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:32:20.043443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.359345ms","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-05-21T19:32:20.04342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:19.647266Z","time spent":"396.098105ms","remote":"127.0.0.1:32910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-21T19:32:20.043487Z","caller":"traceutil/trace.go:171","msg":"trace[787374522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"222.445208ms","start":"2026-05-21T19:32:19.82103Z","end":"2026-05-21T19:32:20.043476Z","steps":["trace[787374522] 'agreement among raft nodes before linearized reading' (duration: 222.282034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:20.059505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.427319ms","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-05-21T19:32:20.05961Z","caller":"traceutil/trace.go:171","msg":"trace[1721919321] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"195.544301ms","start":"2026-05-21T19:32:19.863996Z","end":"2026-05-21T19:32:20.059541Z","steps":["trace[1721919321] 'agreement among raft nodes before linearized reading' (duration: 195.430929ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:26.156937Z","caller":"traceutil/trace.go:171","msg":"trace[2059620285] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"426.636764ms","start":"2026-05-21T19:32:25.730261Z","end":"2026-05-21T19:32:26.156897Z","steps":["trace[2059620285] 'process raft request' (duration: 425.397667ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:26.157593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:25.730237Z","time spent":"426.942012ms","remote":"127.0.0.1:32946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036105,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:32:27.099492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.290852ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:32:27.099712Z","caller":"traceutil/trace.go:171","msg":"trace[5072716] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"934.274526ms","start":"2026-05-21T19:32:26.165405Z","end":"2026-05-21T19:32:27.099679Z","steps":["trace[5072716] 'process raft request' (duration: 212.356651ms)","trace[5072716] 'compare' (duration: 720.577025ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:32:27.099773Z","caller":"traceutil/trace.go:171","msg":"trace[1548745875] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"875.107141ms","start":"2026-05-21T19:32:26.22465Z","end":"2026-05-21T19:32:27.099757Z","steps":["trace[1548745875] 'process raft request' (duration: 875.06674ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:27.099833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.224635Z","time spent":"875.173622ms","remote":"127.0.0.1:33062","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:32:27.099871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.165367Z","time spent":"934.41131ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:32:27.10002Z","caller":"traceutil/trace.go:171","msg":"trace[864331895] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:651; }","duration":"913.915026ms","start":"2026-05-21T19:32:26.186019Z","end":"2026-05-21T19:32:27.099934Z","steps":["trace[864331895] 'read index received' (duration: 11.448828ms)","trace[864331895] 'applied index is now lower than readState.Index' (duration: 902.459948ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:32:27.100175Z","caller":"traceutil/trace.go:171","msg":"trace[1031025454] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"925.996578ms","start":"2026-05-21T19:32:26.174167Z","end":"2026-05-21T19:32:27.100164Z","steps":["trace[1031025454] 'process raft request' (duration: 925.502357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:27.100251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.174147Z","time spent":"926.07206ms","remote":"127.0.0.1:33050","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:32:27.100351Z","caller":"traceutil/trace.go:171","msg":"trace[772517616] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"933.980399ms","start":"2026-05-21T19:32:26.166345Z","end":"2026-05-21T19:32:27.100325Z","steps":["trace[772517616] 'process raft request' (duration: 933.238892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:27.100478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"914.465089ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:32:27.100514Z","caller":"traceutil/trace.go:171","msg":"trace[350365662] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"914.52444ms","start":"2026-05-21T19:32:26.185978Z","end":"2026-05-21T19:32:27.100503Z","steps":["trace[350365662] 'agreement among raft nodes before linearized reading' (duration: 914.482879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:27.100439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"887.026929ms","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-05-21T19:32:27.100614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.18596Z","time spent":"914.644713ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:32:27.10061Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.166324Z","time spent":"934.175354ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:32:27.100634Z","caller":"traceutil/trace.go:171","msg":"trace[1237229634] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"887.237104ms","start":"2026-05-21T19:32:26.213371Z","end":"2026-05-21T19:32:27.100609Z","steps":["trace[1237229634] 'agreement among raft nodes before linearized reading' (duration: 886.965848ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:27.101095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:26.213355Z","time spent":"887.704964ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-21T19:32:27.321252Z","caller":"traceutil/trace.go:171","msg":"trace[1702279246] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"210.656572ms","start":"2026-05-21T19:32:27.110567Z","end":"2026-05-21T19:32:27.321224Z","steps":["trace[1702279246] 'process raft request' (duration: 171.960779ms)","trace[1702279246] 'compare' (duration: 38.57452ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:32:28.520854Z","caller":"traceutil/trace.go:171","msg":"trace[1447041496] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"989.936151ms","start":"2026-05-21T19:32:27.530893Z","end":"2026-05-21T19:32:28.520829Z","steps":["trace[1447041496] 'process raft request' (duration: 989.410529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:28.521057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:27.530855Z","time spent":"990.083575ms","remote":"127.0.0.1:32946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036161,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:32:28.564748Z","caller":"traceutil/trace.go:171","msg":"trace[2111524644] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:656; }","duration":"379.111523ms","start":"2026-05-21T19:32:28.185606Z","end":"2026-05-21T19:32:28.564717Z","steps":["trace[2111524644] 'read index received' (duration: 334.573688ms)","trace[2111524644] 'applied index is now lower than readState.Index' (duration: 44.536485ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:32:28.564887Z","caller":"traceutil/trace.go:171","msg":"trace[1964323619] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"520.958952ms","start":"2026-05-21T19:32:28.043917Z","end":"2026-05-21T19:32:28.564876Z","steps":["trace[1964323619] 'process raft request' (duration: 519.748805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:28.565067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:28.04389Z","time spent":"521.033734ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:32:28.565061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.429929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:32:28.565147Z","caller":"traceutil/trace.go:171","msg":"trace[763809497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"379.571223ms","start":"2026-05-21T19:32:28.185556Z","end":"2026-05-21T19:32:28.565127Z","steps":["trace[763809497] 'agreement among raft nodes before linearized reading' (duration: 379.414529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:28.565189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:28.185538Z","time spent":"379.644384ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:32:28.565629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.182575ms","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-05-21T19:32:28.565675Z","caller":"traceutil/trace.go:171","msg":"trace[1887788774] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"360.258227ms","start":"2026-05-21T19:32:28.205399Z","end":"2026-05-21T19:32:28.565657Z","steps":["trace[1887788774] 'agreement among raft nodes before linearized reading' (duration: 360.106033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:28.565699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:28.20538Z","time spent":"360.314798ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-21T19:32:29.133637Z","caller":"traceutil/trace.go:171","msg":"trace[204414553] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"118.917972ms","start":"2026-05-21T19:32:29.014666Z","end":"2026-05-21T19:32:29.133584Z","steps":["trace[204414553] 'process raft request' (duration: 116.495407ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:30.569556Z","caller":"traceutil/trace.go:171","msg":"trace[1141047277] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"146.218809ms","start":"2026-05-21T19:32:30.423312Z","end":"2026-05-21T19:32:30.569531Z","steps":["trace[1141047277] 'process raft request' (duration: 146.090406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:33.554294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.570343ms","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":"warn","ts":"2026-05-21T19:32:33.554332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.032387ms","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-05-21T19:32:33.554389Z","caller":"traceutil/trace.go:171","msg":"trace[33385925] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"133.713687ms","start":"2026-05-21T19:32:33.420653Z","end":"2026-05-21T19:32:33.554366Z","steps":["trace[33385925] 'range keys from in-memory index tree' (duration: 133.310917ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:33.554464Z","caller":"traceutil/trace.go:171","msg":"trace[1478080339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"190.16865ms","start":"2026-05-21T19:32:33.364228Z","end":"2026-05-21T19:32:33.554396Z","steps":["trace[1478080339] 'range keys from in-memory index tree' (duration: 189.799232ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:32:44.713765Z","caller":"traceutil/trace.go:171","msg":"trace[2116165455] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:829; }","duration":"266.224706ms","start":"2026-05-21T19:32:44.447517Z","end":"2026-05-21T19:32:44.713742Z","steps":["trace[2116165455] 'read index received' (duration: 266.049632ms)","trace[2116165455] 'applied index is now lower than readState.Index' (duration: 174.154µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:32:44.71635Z","caller":"traceutil/trace.go:171","msg":"trace[2046513591] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"448.874937ms","start":"2026-05-21T19:32:44.267445Z","end":"2026-05-21T19:32:44.716312Z","steps":["trace[2046513591] 'process raft request' (duration: 446.176996ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:44.716376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.819884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:32:44.716496Z","caller":"traceutil/trace.go:171","msg":"trace[1497379539] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:799; }","duration":"269.009078ms","start":"2026-05-21T19:32:44.447462Z","end":"2026-05-21T19:32:44.716471Z","steps":["trace[1497379539] 'agreement among raft nodes before linearized reading' (duration: 268.763743ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:32:44.716516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:32:44.267407Z","time spent":"449.02141ms","remote":"127.0.0.1:33110","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:32:49.81966Z","caller":"traceutil/trace.go:171","msg":"trace[695429448] transaction","detail":"{read_only:false; response_revision:868; number_of_response:1; }","duration":"137.925521ms","start":"2026-05-21T19:32:49.681608Z","end":"2026-05-21T19:32:49.819534Z","steps":["trace[695429448] 'process raft request' (duration: 136.904358ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:19.341189Z","caller":"traceutil/trace.go:171","msg":"trace[67442968] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"441.3435ms","start":"2026-05-21T19:33:18.899824Z","end":"2026-05-21T19:33:19.341168Z","steps":["trace[67442968] 'process raft request' (duration: 441.167945ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:19.34133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:18.899796Z","time spent":"441.458993ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:19.999434Z","caller":"traceutil/trace.go:171","msg":"trace[196463890] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"413.828799ms","start":"2026-05-21T19:33:19.585581Z","end":"2026-05-21T19:33:19.99941Z","steps":["trace[196463890] 'process raft request' (duration: 413.164434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:19.999616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:19.585554Z","time spent":"413.948562ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:21.425472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.431466ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:33:21.42557Z","caller":"traceutil/trace.go:171","msg":"trace[1151505353] linearizableReadLoop","detail":"{readStateIndex:1062; appliedIndex:1060; }","duration":"1.619520039s","start":"2026-05-21T19:33:19.806037Z","end":"2026-05-21T19:33:21.425557Z","steps":["trace[1151505353] 'read index received' (duration: 192.975411ms)","trace[1151505353] 'applied index is now lower than readState.Index' (duration: 1.426543668s)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:21.425627Z","caller":"traceutil/trace.go:171","msg":"trace[210528175] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"1.752028144s","start":"2026-05-21T19:33:19.673591Z","end":"2026-05-21T19:33:21.425619Z","steps":["trace[210528175] 'process raft request' (duration: 1.061385623s)","trace[210528175] 'compare' (duration: 689.926373ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:21.425661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:19.673566Z","time spent":"1.752073744s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:21.427516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.621482383s","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-05-21T19:33:21.427545Z","caller":"traceutil/trace.go:171","msg":"trace[316704568] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"1.621544325s","start":"2026-05-21T19:33:19.805992Z","end":"2026-05-21T19:33:21.427536Z","steps":["trace[316704568] 'agreement among raft nodes before linearized reading' (duration: 1.619771965s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:21.427562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:19.80597Z","time spent":"1.621588896s","remote":"127.0.0.1:33142","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-05-21T19:33:21.478364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.379847ms","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-05-21T19:33:21.478423Z","caller":"traceutil/trace.go:171","msg":"trace[1366960796] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1021; }","duration":"123.492319ms","start":"2026-05-21T19:33:21.354917Z","end":"2026-05-21T19:33:21.47841Z","steps":["trace[1366960796] 'agreement among raft nodes before linearized reading' (duration: 123.386367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:21.478561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.149654005s","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-05-21T19:33:21.478582Z","caller":"traceutil/trace.go:171","msg":"trace[1100317649] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"1.149707297s","start":"2026-05-21T19:33:20.328868Z","end":"2026-05-21T19:33:21.478575Z","steps":["trace[1100317649] 'agreement among raft nodes before linearized reading' (duration: 1.149644595s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:21.478601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:20.328851Z","time spent":"1.149745078s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:33:21.47881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330002516s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:33:21.478786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.294256028s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:21.478834Z","caller":"traceutil/trace.go:171","msg":"trace[1150913418] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:1022; }","duration":"1.330028057s","start":"2026-05-21T19:33:20.148797Z","end":"2026-05-21T19:33:21.478825Z","steps":["trace[1150913418] 'agreement among raft nodes before linearized reading' (duration: 1.329984196s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:21.478858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:20.148773Z","time spent":"1.330079778s","remote":"127.0.0.1:33272","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:33:21.478857Z","caller":"traceutil/trace.go:171","msg":"trace[1022159455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1022; }","duration":"1.29436543s","start":"2026-05-21T19:33:20.184479Z","end":"2026-05-21T19:33:21.478845Z","steps":["trace[1022159455] 'agreement among raft nodes before linearized reading' (duration: 1.294264488s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:21.478886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:20.184461Z","time spent":"1.294417241s","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:33:43.774462Z","caller":"traceutil/trace.go:171","msg":"trace[137196860] transaction","detail":"{read_only:false; response_revision:1097; number_of_response:1; }","duration":"123.804305ms","start":"2026-05-21T19:33:43.650621Z","end":"2026-05-21T19:33:43.774426Z","steps":["trace[137196860] 'process raft request' (duration: 123.724614ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:43.774616Z","caller":"traceutil/trace.go:171","msg":"trace[874276093] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"122.868733ms","start":"2026-05-21T19:33:43.651732Z","end":"2026-05-21T19:33:43.7746Z","steps":["trace[874276093] 'process raft request' (duration: 122.657099ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:43.774516Z","caller":"traceutil/trace.go:171","msg":"trace[1487178779] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"123.873047ms","start":"2026-05-21T19:33:43.650611Z","end":"2026-05-21T19:33:43.774485Z","steps":["trace[1487178779] 'process raft request' (duration: 123.536159ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:45.037618Z","caller":"traceutil/trace.go:171","msg":"trace[1231586036] linearizableReadLoop","detail":"{readStateIndex:1145; appliedIndex:1144; }","duration":"204.79577ms","start":"2026-05-21T19:33:44.832797Z","end":"2026-05-21T19:33:45.037592Z","steps":["trace[1231586036] 'read index received' (duration: 204.610416ms)","trace[1231586036] 'applied index is now lower than readState.Index' (duration: 184.074µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:45.037761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.938953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:45.037771Z","caller":"traceutil/trace.go:171","msg":"trace[441997024] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"802.907063ms","start":"2026-05-21T19:33:44.234802Z","end":"2026-05-21T19:33:45.03771Z","steps":["trace[441997024] 'process raft request' (duration: 802.607766ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:45.037799Z","caller":"traceutil/trace.go:171","msg":"trace[1394730329] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1099; }","duration":"205.051146ms","start":"2026-05-21T19:33:44.832733Z","end":"2026-05-21T19:33:45.037784Z","steps":["trace[1394730329] 'agreement among raft nodes before linearized reading' (duration: 204.960894ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:45.038273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:44.23479Z","time spent":"803.102657ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:33:45.351294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.930131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T19:33:45.351388Z","caller":"traceutil/trace.go:171","msg":"trace[168088681] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1099; }","duration":"173.057484ms","start":"2026-05-21T19:33:45.178311Z","end":"2026-05-21T19:33:45.351369Z","steps":["trace[168088681] 'range keys from in-memory index tree' (duration: 172.790008ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:47.030349Z","caller":"traceutil/trace.go:171","msg":"trace[2082308897] transaction","detail":"{read_only:false; response_revision:1106; number_of_response:1; }","duration":"207.422642ms","start":"2026-05-21T19:33:46.822901Z","end":"2026-05-21T19:33:47.030324Z","steps":["trace[2082308897] 'process raft request' (duration: 207.261738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:47.322518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.650101ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:33:47.322654Z","caller":"traceutil/trace.go:171","msg":"trace[1387760808] linearizableReadLoop","detail":"{readStateIndex:1154; appliedIndex:1153; }","duration":"137.142743ms","start":"2026-05-21T19:33:47.185495Z","end":"2026-05-21T19:33:47.322637Z","steps":["trace[1387760808] 'read index received' (duration: 45.611µs)","trace[1387760808] 'applied index is now lower than readState.Index' (duration: 137.095662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:47.322739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.243465ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:47.322787Z","caller":"traceutil/trace.go:171","msg":"trace[440598780] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1107; }","duration":"137.329767ms","start":"2026-05-21T19:33:47.185446Z","end":"2026-05-21T19:33:47.322776Z","steps":["trace[440598780] 'agreement among raft nodes before linearized reading' (duration: 137.237745ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:47.322893Z","caller":"traceutil/trace.go:171","msg":"trace[1624138024] transaction","detail":"{read_only:false; response_revision:1107; number_of_response:1; }","duration":"277.542369ms","start":"2026-05-21T19:33:47.045311Z","end":"2026-05-21T19:33:47.322853Z","steps":["trace[1624138024] 'process raft request' (duration: 102.441218ms)","trace[1624138024] 'compare' (duration: 174.466106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:33:48.192712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027542862767518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:33:48.693387Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027542862767518,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:33:48.819691Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.137410335s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:33:48.820095Z","caller":"traceutil/trace.go:171","msg":"trace[1382454398] linearizableReadLoop","detail":"{readStateIndex:1155; appliedIndex:1154; }","duration":"1.127766364s","start":"2026-05-21T19:33:47.692302Z","end":"2026-05-21T19:33:48.820068Z","steps":["trace[1382454398] 'read index received' (duration: 1.127561409s)","trace[1382454398] 'applied index is now lower than readState.Index' (duration: 203.985µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:48.820226Z","caller":"traceutil/trace.go:171","msg":"trace[694477969] transaction","detail":"{read_only:false; response_revision:1108; number_of_response:1; }","duration":"1.138017799s","start":"2026-05-21T19:33:47.68217Z","end":"2026-05-21T19:33:48.820188Z","steps":["trace[694477969] 'process raft request' (duration: 1.137760012s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.127995649s","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-05-21T19:33:48.820343Z","caller":"traceutil/trace.go:171","msg":"trace[140302965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1108; }","duration":"1.128065141s","start":"2026-05-21T19:33:47.692266Z","end":"2026-05-21T19:33:48.820331Z","steps":["trace[140302965] 'agreement among raft nodes before linearized reading' (duration: 1.127910598s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.692256Z","time spent":"1.128108872s","remote":"127.0.0.1:33142","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-05-21T19:33:48.820385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005922623s","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-05-21T19:33:48.820441Z","caller":"traceutil/trace.go:171","msg":"trace[1730140474] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1108; }","duration":"1.006004204s","start":"2026-05-21T19:33:47.814419Z","end":"2026-05-21T19:33:48.820423Z","steps":["trace[1730140474] 'agreement among raft nodes before linearized reading' (duration: 1.00581745s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.814407Z","time spent":"1.006064306s","remote":"127.0.0.1:33142","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-05-21T19:33:48.820481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.721955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-21T19:33:48.820544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005435902s","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-05-21T19:33:48.820578Z","caller":"traceutil/trace.go:171","msg":"trace[1807946668] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1108; }","duration":"1.005495693s","start":"2026-05-21T19:33:47.815072Z","end":"2026-05-21T19:33:48.820568Z","steps":["trace[1807946668] 'agreement among raft nodes before linearized reading' (duration: 1.005408991s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.293606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:48.820604Z","caller":"traceutil/trace.go:171","msg":"trace[1432386494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1108; }","duration":"632.810157ms","start":"2026-05-21T19:33:48.187729Z","end":"2026-05-21T19:33:48.820539Z","steps":["trace[1432386494] 'agreement among raft nodes before linearized reading' (duration: 632.677684ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:48.820656Z","caller":"traceutil/trace.go:171","msg":"trace[1542457002] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1108; }","duration":"615.45897ms","start":"2026-05-21T19:33:48.205176Z","end":"2026-05-21T19:33:48.820635Z","steps":["trace[1542457002] 'agreement among raft nodes before linearized reading' (duration: 615.249236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.005932413s","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-05-21T19:33:48.820719Z","caller":"traceutil/trace.go:171","msg":"trace[209493356] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1108; }","duration":"1.005985604s","start":"2026-05-21T19:33:47.81472Z","end":"2026-05-21T19:33:48.820706Z","steps":["trace[209493356] 'agreement among raft nodes before linearized reading' (duration: 1.00579874s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:48.820629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.815059Z","time spent":"1.005561055s","remote":"127.0.0.1:33142","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-05-21T19:33:48.82066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.187667Z","time spent":"632.982351ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:33:48.820759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.814701Z","time spent":"1.006043766s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:33:48.82071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:48.205158Z","time spent":"615.539272ms","remote":"127.0.0.1:33242","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:33:48.820342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:47.682147Z","time spent":"1.138124962s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:33:48.992536Z","caller":"traceutil/trace.go:171","msg":"trace[1947107359] transaction","detail":"{read_only:false; response_revision:1110; number_of_response:1; }","duration":"157.503168ms","start":"2026-05-21T19:33:48.835004Z","end":"2026-05-21T19:33:48.992507Z","steps":["trace[1947107359] 'process raft request' (duration: 118.693299ms)","trace[1947107359] 'compare' (duration: 38.350829ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:33:48.992571Z","caller":"traceutil/trace.go:171","msg":"trace[1544379511] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"156.69718ms","start":"2026-05-21T19:33:48.83585Z","end":"2026-05-21T19:33:48.992547Z","steps":["trace[1544379511] 'process raft request' (duration: 156.410834ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:33:48.993092Z","caller":"traceutil/trace.go:171","msg":"trace[1741270070] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"157.086159ms","start":"2026-05-21T19:33:48.835935Z","end":"2026-05-21T19:33:48.993021Z","steps":["trace[1741270070] 'process raft request' (duration: 156.406524ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:49.52467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.150103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:33:49.524822Z","caller":"traceutil/trace.go:171","msg":"trace[67305714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1112; }","duration":"333.308276ms","start":"2026-05-21T19:33:49.191494Z","end":"2026-05-21T19:33:49.524802Z","steps":["trace[67305714] 'range keys from in-memory index tree' (duration: 333.076961ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:33:49.524889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:33:49.19144Z","time spent":"333.438889ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:33:49.525014Z","caller":"traceutil/trace.go:171","msg":"trace[1101366294] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"193.721399ms","start":"2026-05-21T19:33:49.331282Z","end":"2026-05-21T19:33:49.525003Z","steps":["trace[1101366294] 'process raft request' (duration: 166.871884ms)","trace[1101366294] 'compare' (duration: 26.61749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:05.685389Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027542862767755,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:05.767285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:05.183896Z","time spent":"583.382323ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-21T19:34:05.767349Z","caller":"traceutil/trace.go:171","msg":"trace[1604239349] linearizableReadLoop","detail":"{readStateIndex:1269; appliedIndex:1267; }","duration":"582.450783ms","start":"2026-05-21T19:34:05.184878Z","end":"2026-05-21T19:34:05.767328Z","steps":["trace[1604239349] 'read index received' (duration: 575.752739ms)","trace[1604239349] 'applied index is now lower than readState.Index' (duration: 6.697114ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:05.767565Z","caller":"traceutil/trace.go:171","msg":"trace[539578350] transaction","detail":"{read_only:false; response_revision:1216; number_of_response:1; }","duration":"197.435712ms","start":"2026-05-21T19:34:05.570117Z","end":"2026-05-21T19:34:05.767553Z","steps":["trace[539578350] 'process raft request' (duration: 197.105584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:05.767727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.136205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:05.767773Z","caller":"traceutil/trace.go:171","msg":"trace[256265739] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1216; }","duration":"479.197446ms","start":"2026-05-21T19:34:05.28856Z","end":"2026-05-21T19:34:05.767757Z","steps":["trace[256265739] 'agreement among raft nodes before linearized reading' (duration: 479.102084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:05.767807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:05.288515Z","time spent":"479.284328ms","remote":"127.0.0.1:33148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":5,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:05.767873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"582.977164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:05.76791Z","caller":"traceutil/trace.go:171","msg":"trace[380873651] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1216; }","duration":"583.042745ms","start":"2026-05-21T19:34:05.184856Z","end":"2026-05-21T19:34:05.767899Z","steps":["trace[380873651] 'agreement among raft nodes before linearized reading' (duration: 582.976034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:05.767943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:05.184845Z","time spent":"583.087156ms","remote":"127.0.0.1:32876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-21T19:34:19.549988Z","caller":"traceutil/trace.go:171","msg":"trace[1632685751] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"400.002763ms","start":"2026-05-21T19:34:19.149959Z","end":"2026-05-21T19:34:19.549961Z","steps":["trace[1632685751] 'read index received' (duration: 399.824969ms)","trace[1632685751] 'applied index is now lower than readState.Index' (duration: 176.564µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:19.550064Z","caller":"traceutil/trace.go:171","msg":"trace[521825846] transaction","detail":"{read_only:false; response_revision:1259; number_of_response:1; }","duration":"402.794137ms","start":"2026-05-21T19:34:19.147242Z","end":"2026-05-21T19:34:19.550036Z","steps":["trace[521825846] 'process raft request' (duration: 402.548172ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:19.550221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:19.14721Z","time spent":"402.9102ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:19.55024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.268329ms","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-05-21T19:34:19.550283Z","caller":"traceutil/trace.go:171","msg":"trace[983849065] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1259; }","duration":"400.33022ms","start":"2026-05-21T19:34:19.149942Z","end":"2026-05-21T19:34:19.550273Z","steps":["trace[983849065] 'agreement among raft nodes before linearized reading' (duration: 400.172067ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:19.550314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:19.149935Z","time spent":"400.372171ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-21T19:34:19.550348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.048378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"warn","ts":"2026-05-21T19:34:19.550369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.164818ms","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-05-21T19:34:19.550427Z","caller":"traceutil/trace.go:171","msg":"trace[1012766519] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1259; }","duration":"381.179531ms","start":"2026-05-21T19:34:19.169226Z","end":"2026-05-21T19:34:19.550406Z","steps":["trace[1012766519] 'agreement among raft nodes before linearized reading' (duration: 380.932926ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:19.550433Z","caller":"traceutil/trace.go:171","msg":"trace[1296866493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1259; }","duration":"380.26635ms","start":"2026-05-21T19:34:19.17015Z","end":"2026-05-21T19:34:19.550416Z","steps":["trace[1296866493] 'agreement among raft nodes before linearized reading' (duration: 380.046135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:19.550473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:19.170138Z","time spent":"380.328672ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:19.550473Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:19.169207Z","time spent":"381.257854ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:22.061405Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027542862767900,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:22.562057Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071027542862767900,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-21T19:34:22.712646Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.152555551s","expected-duration":"1s"} {"level":"info","ts":"2026-05-21T19:34:22.713454Z","caller":"traceutil/trace.go:171","msg":"trace[516078142] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"1.153452151s","start":"2026-05-21T19:34:21.55996Z","end":"2026-05-21T19:34:22.713413Z","steps":["trace[516078142] 'process raft request' (duration: 1.153146374s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:22.713734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.559937Z","time spent":"1.153623175s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:23.0253Z","caller":"traceutil/trace.go:171","msg":"trace[935016954] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1328; }","duration":"1.464677411s","start":"2026-05-21T19:34:21.560599Z","end":"2026-05-21T19:34:23.025276Z","steps":["trace[935016954] 'read index received' (duration: 1.152339196s)","trace[935016954] 'applied index is now lower than readState.Index' (duration: 312.337305ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:23.02546Z","caller":"traceutil/trace.go:171","msg":"trace[816217322] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"1.206002847s","start":"2026-05-21T19:34:21.819445Z","end":"2026-05-21T19:34:23.025448Z","steps":["trace[816217322] 'process raft request' (duration: 1.20569764s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:23.025567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.819424Z","time spent":"1.206068478s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:23.025748Z","caller":"traceutil/trace.go:171","msg":"trace[1422921675] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"449.135328ms","start":"2026-05-21T19:34:22.576582Z","end":"2026-05-21T19:34:23.025717Z","steps":["trace[1422921675] 'process raft request' (duration: 448.623866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:23.025861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"923.057585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:23.025908Z","caller":"traceutil/trace.go:171","msg":"trace[183292685] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1277; }","duration":"923.142187ms","start":"2026-05-21T19:34:22.102754Z","end":"2026-05-21T19:34:23.025896Z","steps":["trace[183292685] 'agreement among raft nodes before linearized reading' (duration: 923.070685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:23.025911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:22.576546Z","time spent":"449.281671ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:23.025931Z","caller":"traceutil/trace.go:171","msg":"trace[448280732] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"1.461644372s","start":"2026-05-21T19:34:21.564262Z","end":"2026-05-21T19:34:23.025906Z","steps":["trace[448280732] 'process raft request' (duration: 1.460744282s)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:23.02594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:22.102731Z","time spent":"923.200178ms","remote":"127.0.0.1:36334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"warn","ts":"2026-05-21T19:34:23.025999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.564254Z","time spent":"1.461707203s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:23.026023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.465393878s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-21T19:34:23.02609Z","caller":"traceutil/trace.go:171","msg":"trace[327975245] transaction","detail":"{read_only:false; response_revision:1274; number_of_response:1; }","duration":"1.461891638s","start":"2026-05-21T19:34:21.564177Z","end":"2026-05-21T19:34:23.026069Z","steps":["trace[327975245] 'process raft request' (duration: 1.449116784s)","trace[327975245] 'compare' (duration: 11.165486ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:23.026131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.118452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:23.026122Z","caller":"traceutil/trace.go:171","msg":"trace[1809628158] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1277; }","duration":"1.46552951s","start":"2026-05-21T19:34:21.560568Z","end":"2026-05-21T19:34:23.026097Z","steps":["trace[1809628158] 'agreement among raft nodes before linearized reading' (duration: 1.464928857s)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:23.026164Z","caller":"traceutil/trace.go:171","msg":"trace[1762690613] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1277; }","duration":"839.215785ms","start":"2026-05-21T19:34:22.186938Z","end":"2026-05-21T19:34:23.026154Z","steps":["trace[1762690613] 'agreement among raft nodes before linearized reading' (duration: 839.137383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:23.026191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:22.186899Z","time spent":"839.286416ms","remote":"127.0.0.1:32860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-21T19:34:23.026177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.564163Z","time spent":"1.461969029s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:23.026187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:21.560389Z","time spent":"1.465787016s","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":512,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-21T19:34:24.430905Z","caller":"traceutil/trace.go:171","msg":"trace[1699769729] linearizableReadLoop","detail":"{readStateIndex:1337; appliedIndex:1336; }","duration":"317.575504ms","start":"2026-05-21T19:34:24.113272Z","end":"2026-05-21T19:34:24.430848Z","steps":["trace[1699769729] 'read index received' (duration: 311.175428ms)","trace[1699769729] 'applied index is now lower than readState.Index' (duration: 6.398196ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:24.43096Z","caller":"traceutil/trace.go:171","msg":"trace[2070196461] transaction","detail":"{read_only:false; response_revision:1281; number_of_response:1; }","duration":"403.902822ms","start":"2026-05-21T19:34:24.027009Z","end":"2026-05-21T19:34:24.430912Z","steps":["trace[2070196461] 'process raft request' (duration: 397.510046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:24.431858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.561797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:24.431906Z","caller":"traceutil/trace.go:171","msg":"trace[1180431955] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1281; }","duration":"318.65312ms","start":"2026-05-21T19:34:24.113239Z","end":"2026-05-21T19:34:24.431892Z","steps":["trace[1180431955] 'agreement among raft nodes before linearized reading' (duration: 317.873082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:24.431897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.026983Z","time spent":"404.846664ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:34:24.431947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.113222Z","time spent":"318.713611ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T19:34:24.432012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.248708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:24.432094Z","caller":"traceutil/trace.go:171","msg":"trace[610491605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1281; }","duration":"245.396921ms","start":"2026-05-21T19:34:24.186666Z","end":"2026-05-21T19:34:24.432063Z","steps":["trace[610491605] 'agreement among raft nodes before linearized reading' (duration: 245.251328ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:24.81716Z","caller":"traceutil/trace.go:171","msg":"trace[2138316812] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"360.62083ms","start":"2026-05-21T19:34:24.456505Z","end":"2026-05-21T19:34:24.817126Z","steps":["trace[2138316812] 'read index received' (duration: 358.634195ms)","trace[2138316812] 'applied index is now lower than readState.Index' (duration: 1.984445ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:24.817346Z","caller":"traceutil/trace.go:171","msg":"trace[1011075898] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"380.134926ms","start":"2026-05-21T19:34:24.437193Z","end":"2026-05-21T19:34:24.817328Z","steps":["trace[1011075898] 'process raft request' (duration: 378.016738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:24.817461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.437177Z","time spent":"380.205508ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:34:24.81761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.06415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:24.81771Z","caller":"traceutil/trace.go:171","msg":"trace[1339812015] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:1282; }","duration":"361.232235ms","start":"2026-05-21T19:34:24.45645Z","end":"2026-05-21T19:34:24.817682Z","steps":["trace[1339812015] 'agreement among raft nodes before linearized reading' (duration: 361.025079ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:24.817761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.456421Z","time spent":"361.328217ms","remote":"127.0.0.1:33400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:34:25.27724Z","caller":"traceutil/trace.go:171","msg":"trace[1250205626] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"453.159641ms","start":"2026-05-21T19:34:24.82406Z","end":"2026-05-21T19:34:25.277219Z","steps":["trace[1250205626] 'process raft request' (duration: 428.393123ms)","trace[1250205626] 'compare' (duration: 24.681106ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:25.277355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.824032Z","time spent":"453.257602ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:25.309846Z","caller":"traceutil/trace.go:171","msg":"trace[1487682612] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1338; }","duration":"280.446914ms","start":"2026-05-21T19:34:25.029378Z","end":"2026-05-21T19:34:25.309825Z","steps":["trace[1487682612] 'read index received' (duration: 223.08212ms)","trace[1487682612] 'applied index is now lower than readState.Index' (duration: 57.363794ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:25.309982Z","caller":"traceutil/trace.go:171","msg":"trace[1054624416] transaction","detail":"{read_only:false; response_revision:1287; number_of_response:1; }","duration":"279.378759ms","start":"2026-05-21T19:34:25.030579Z","end":"2026-05-21T19:34:25.309958Z","steps":["trace[1054624416] 'process raft request' (duration: 279.212755ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.310011Z","caller":"traceutil/trace.go:171","msg":"trace[2069308884] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"478.546492ms","start":"2026-05-21T19:34:24.831408Z","end":"2026-05-21T19:34:25.309954Z","steps":["trace[2069308884] 'process raft request' (duration: 478.307567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:25.310132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.179536ms","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-05-21T19:34:25.310159Z","caller":"traceutil/trace.go:171","msg":"trace[2021739960] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1287; }","duration":"276.235357ms","start":"2026-05-21T19:34:25.033912Z","end":"2026-05-21T19:34:25.310148Z","steps":["trace[2021739960] 'agreement among raft nodes before linearized reading' (duration: 276.183206ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.310205Z","caller":"traceutil/trace.go:171","msg":"trace[1109573607] transaction","detail":"{read_only:false; response_revision:1286; number_of_response:1; }","duration":"450.019378ms","start":"2026-05-21T19:34:24.860178Z","end":"2026-05-21T19:34:25.310197Z","steps":["trace[1109573607] 'process raft request' (duration: 449.585018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:25.310269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.831401Z","time spent":"478.722996ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:25.310287Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.86016Z","time spent":"450.063899ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:34:25.310508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.669922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:25.31053Z","caller":"traceutil/trace.go:171","msg":"trace[66680123] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1287; }","duration":"123.736474ms","start":"2026-05-21T19:34:25.186787Z","end":"2026-05-21T19:34:25.310524Z","steps":["trace[66680123] 'agreement among raft nodes before linearized reading' (duration: 123.703423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:25.310683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.121327ms","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-05-21T19:34:25.310718Z","caller":"traceutil/trace.go:171","msg":"trace[1376178988] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1287; }","duration":"242.192398ms","start":"2026-05-21T19:34:25.068517Z","end":"2026-05-21T19:34:25.310709Z","steps":["trace[1376178988] 'agreement among raft nodes before linearized reading' (duration: 242.053674ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.310746Z","caller":"traceutil/trace.go:171","msg":"trace[1151719912] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"485.725027ms","start":"2026-05-21T19:34:24.825013Z","end":"2026-05-21T19:34:25.310738Z","steps":["trace[1151719912] 'process raft request' (duration: 484.606121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:25.310805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:24.825Z","time spent":"485.765918ms","remote":"127.0.0.1:32928","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-21T19:34:25.310884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.629298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-21T19:34:25.310909Z","caller":"traceutil/trace.go:171","msg":"trace[1615579804] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1287; }","duration":"125.717209ms","start":"2026-05-21T19:34:25.185184Z","end":"2026-05-21T19:34:25.310901Z","steps":["trace[1615579804] 'agreement among raft nodes before linearized reading' (duration: 125.642018ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:25.31007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.68679ms","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-05-21T19:34:25.311033Z","caller":"traceutil/trace.go:171","msg":"trace[1798728679] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1287; }","duration":"281.746094ms","start":"2026-05-21T19:34:25.02928Z","end":"2026-05-21T19:34:25.311026Z","steps":["trace[1798728679] 'agreement among raft nodes before linearized reading' (duration: 280.662129ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.561709Z","caller":"traceutil/trace.go:171","msg":"trace[1875842695] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"247.11485ms","start":"2026-05-21T19:34:25.314561Z","end":"2026-05-21T19:34:25.561676Z","steps":["trace[1875842695] 'process raft request' (duration: 246.927046ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.57073Z","caller":"traceutil/trace.go:171","msg":"trace[92334632] linearizableReadLoop","detail":"{readStateIndex:1349; appliedIndex:1343; }","duration":"157.063938ms","start":"2026-05-21T19:34:25.41364Z","end":"2026-05-21T19:34:25.570704Z","steps":["trace[92334632] 'read index received' (duration: 147.808456ms)","trace[92334632] 'applied index is now lower than readState.Index' (duration: 9.254242ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:25.571004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.324344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:25.57106Z","caller":"traceutil/trace.go:171","msg":"trace[566471844] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1292; }","duration":"157.447756ms","start":"2026-05-21T19:34:25.413597Z","end":"2026-05-21T19:34:25.571045Z","steps":["trace[566471844] 'agreement among raft nodes before linearized reading' (duration: 157.224661ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.571926Z","caller":"traceutil/trace.go:171","msg":"trace[1471051751] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"255.854151ms","start":"2026-05-21T19:34:25.316055Z","end":"2026-05-21T19:34:25.571909Z","steps":["trace[1471051751] 'process raft request' (duration: 254.372056ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.572136Z","caller":"traceutil/trace.go:171","msg":"trace[245755306] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"256.012825ms","start":"2026-05-21T19:34:25.316109Z","end":"2026-05-21T19:34:25.572121Z","steps":["trace[245755306] 'process raft request' (duration: 254.489069ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.572309Z","caller":"traceutil/trace.go:171","msg":"trace[2008324631] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"256.143288ms","start":"2026-05-21T19:34:25.316151Z","end":"2026-05-21T19:34:25.572295Z","steps":["trace[2008324631] 'process raft request' (duration: 254.482419ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:25.572452Z","caller":"traceutil/trace.go:171","msg":"trace[1589396391] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"256.175518ms","start":"2026-05-21T19:34:25.316267Z","end":"2026-05-21T19:34:25.572442Z","steps":["trace[1589396391] 'process raft request' (duration: 254.398967ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:27.492249Z","caller":"traceutil/trace.go:171","msg":"trace[1486992766] transaction","detail":"{read_only:false; response_revision:1294; number_of_response:1; }","duration":"178.227391ms","start":"2026-05-21T19:34:27.313989Z","end":"2026-05-21T19:34:27.492216Z","steps":["trace[1486992766] 'process raft request' (duration: 178.055788ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:27.728164Z","caller":"traceutil/trace.go:171","msg":"trace[964339088] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1351; }","duration":"152.36463ms","start":"2026-05-21T19:34:27.575776Z","end":"2026-05-21T19:34:27.728141Z","steps":["trace[964339088] 'read index received' (duration: 152.174395ms)","trace[964339088] 'applied index is now lower than readState.Index' (duration: 189.185µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:27.728703Z","caller":"traceutil/trace.go:171","msg":"trace[857827323] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"414.071445ms","start":"2026-05-21T19:34:27.314618Z","end":"2026-05-21T19:34:27.728689Z","steps":["trace[857827323] 'process raft request' (duration: 413.368428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:27.72881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:27.314607Z","time spent":"414.136596ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:27.729507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.722971ms","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-05-21T19:34:27.729552Z","caller":"traceutil/trace.go:171","msg":"trace[1759166640] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1295; }","duration":"153.808643ms","start":"2026-05-21T19:34:27.57573Z","end":"2026-05-21T19:34:27.729539Z","steps":["trace[1759166640] 'agreement among raft nodes before linearized reading' (duration: 153.6735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:27.729685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.012065ms","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-05-21T19:34:27.729709Z","caller":"traceutil/trace.go:171","msg":"trace[1491938469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1295; }","duration":"153.064907ms","start":"2026-05-21T19:34:27.576637Z","end":"2026-05-21T19:34:27.729701Z","steps":["trace[1491938469] 'agreement among raft nodes before linearized reading' (duration: 152.995825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:27.729812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.350219ms","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-05-21T19:34:27.729833Z","caller":"traceutil/trace.go:171","msg":"trace[1205992646] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1295; }","duration":"152.410591ms","start":"2026-05-21T19:34:27.577416Z","end":"2026-05-21T19:34:27.729827Z","steps":["trace[1205992646] 'agreement among raft nodes before linearized reading' (duration: 152.348229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:27.729927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.111788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:490"} {"level":"info","ts":"2026-05-21T19:34:27.729948Z","caller":"traceutil/trace.go:171","msg":"trace[1594958821] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1295; }","duration":"153.150948ms","start":"2026-05-21T19:34:27.576791Z","end":"2026-05-21T19:34:27.729942Z","steps":["trace[1594958821] 'agreement among raft nodes before linearized reading' (duration: 153.094267ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:35.496687Z","caller":"traceutil/trace.go:171","msg":"trace[55470302] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1425; }","duration":"168.399079ms","start":"2026-05-21T19:34:35.328263Z","end":"2026-05-21T19:34:35.496662Z","steps":["trace[55470302] 'read index received' (duration: 168.204424ms)","trace[55470302] 'applied index is now lower than readState.Index' (duration: 193.364µs)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:35.496711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.186021Z","time spent":"310.686147ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-21T19:34:35.496908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.616723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"info","ts":"2026-05-21T19:34:35.496994Z","caller":"traceutil/trace.go:171","msg":"trace[297752329] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1367; }","duration":"168.735276ms","start":"2026-05-21T19:34:35.328248Z","end":"2026-05-21T19:34:35.496983Z","steps":["trace[297752329] 'agreement among raft nodes before linearized reading' (duration: 168.504781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.483871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"890.693753ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-21T19:34:36.484165Z","caller":"traceutil/trace.go:171","msg":"trace[897230620] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"728.974408ms","start":"2026-05-21T19:34:35.755171Z","end":"2026-05-21T19:34:36.484145Z","steps":["trace[897230620] 'process raft request' (duration: 728.930307ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.484245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.755144Z","time spent":"729.06564ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:36.484445Z","caller":"traceutil/trace.go:171","msg":"trace[1263969385] transaction","detail":"{read_only:false; response_revision:1368; number_of_response:1; }","duration":"985.894273ms","start":"2026-05-21T19:34:35.498539Z","end":"2026-05-21T19:34:36.484433Z","steps":["trace[1263969385] 'process raft request' (duration: 94.545416ms)","trace[1263969385] 'compare' (duration: 890.481318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-21T19:34:36.484503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.498513Z","time spent":"985.963415ms","remote":"127.0.0.1:32896","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-21T19:34:36.484737Z","caller":"traceutil/trace.go:171","msg":"trace[989017668] linearizableReadLoop","detail":"{readStateIndex:1428; appliedIndex:1426; }","duration":"956.961081ms","start":"2026-05-21T19:34:35.527702Z","end":"2026-05-21T19:34:36.484663Z","steps":["trace[989017668] 'read index received' (duration: 65.521561ms)","trace[989017668] 'applied index is now lower than readState.Index' (duration: 891.43678ms)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:34:36.484877Z","caller":"traceutil/trace.go:171","msg":"trace[1214469248] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"983.56049ms","start":"2026-05-21T19:34:35.501302Z","end":"2026-05-21T19:34:36.484863Z","steps":["trace[1214469248] 'process raft request' (duration: 982.67874ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.485014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.501281Z","time spent":"983.627481ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-21T19:34:36.485281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.564057ms","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-05-21T19:34:36.485302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.367003ms","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-05-21T19:34:36.485351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.571605ms","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-05-21T19:34:36.485453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"957.759089ms","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-05-21T19:34:36.485483Z","caller":"traceutil/trace.go:171","msg":"trace[453110215] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1370; }","duration":"957.818291ms","start":"2026-05-21T19:34:35.527654Z","end":"2026-05-21T19:34:36.485472Z","steps":["trace[453110215] 'agreement among raft nodes before linearized reading' (duration: 957.746489ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:36.485488Z","caller":"traceutil/trace.go:171","msg":"trace[2120743469] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1370; }","duration":"700.65662ms","start":"2026-05-21T19:34:35.784667Z","end":"2026-05-21T19:34:36.485323Z","steps":["trace[2120743469] 'agreement among raft nodes before linearized reading' (duration: 700.392564ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.48551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.527638Z","time spent":"957.866222ms","remote":"127.0.0.1:33142","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-05-21T19:34:36.485578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.581717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-21T19:34:36.485634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.74453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-21T19:34:36.485646Z","caller":"traceutil/trace.go:171","msg":"trace[1073358458] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:1370; }","duration":"201.665789ms","start":"2026-05-21T19:34:36.283957Z","end":"2026-05-21T19:34:36.485623Z","steps":["trace[1073358458] 'agreement among raft nodes before linearized reading' (duration: 201.564337ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:36.48564Z","caller":"traceutil/trace.go:171","msg":"trace[2032420108] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1370; }","duration":"693.422304ms","start":"2026-05-21T19:34:35.791905Z","end":"2026-05-21T19:34:36.485328Z","steps":["trace[2032420108] 'agreement among raft nodes before linearized reading' (duration: 693.314001ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:36.485691Z","caller":"traceutil/trace.go:171","msg":"trace[421230604] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1370; }","duration":"283.826612ms","start":"2026-05-21T19:34:36.201848Z","end":"2026-05-21T19:34:36.485675Z","steps":["trace[421230604] 'agreement among raft nodes before linearized reading' (duration: 283.743441ms)"],"step_count":1} {"level":"info","ts":"2026-05-21T19:34:36.485726Z","caller":"traceutil/trace.go:171","msg":"trace[1559268038] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1370; }","duration":"692.651217ms","start":"2026-05-21T19:34:35.792734Z","end":"2026-05-21T19:34:36.485386Z","steps":["trace[1559268038] 'agreement among raft nodes before linearized reading' (duration: 692.504563ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.485754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.792721Z","time spent":"693.027616ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-21T19:34:36.485664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.784648Z","time spent":"700.879906ms","remote":"127.0.0.1:33142","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-05-21T19:34:36.485865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.622066ms","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-05-21T19:34:36.485922Z","caller":"traceutil/trace.go:171","msg":"trace[960042138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1370; }","duration":"695.697617ms","start":"2026-05-21T19:34:35.790213Z","end":"2026-05-21T19:34:36.485911Z","steps":["trace[960042138] 'agreement among raft nodes before linearized reading' (duration: 695.580544ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.485455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.540681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"warn","ts":"2026-05-21T19:34:36.485952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.790206Z","time spent":"695.739958ms","remote":"127.0.0.1:33142","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-05-21T19:34:36.485701Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.791896Z","time spent":"693.796353ms","remote":"127.0.0.1:33142","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-05-21T19:34:36.485995Z","caller":"traceutil/trace.go:171","msg":"trace[1082452137] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1370; }","duration":"547.140844ms","start":"2026-05-21T19:34:35.938828Z","end":"2026-05-21T19:34:36.485969Z","steps":["trace[1082452137] 'agreement among raft nodes before linearized reading' (duration: 546.388156ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.486053Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.938806Z","time spent":"547.229576ms","remote":"127.0.0.1:33142","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":557,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-05-21T19:34:36.486159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"699.707228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:34:36.486499Z","caller":"traceutil/trace.go:171","msg":"trace[994386210] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:1370; }","duration":"700.246181ms","start":"2026-05-21T19:34:35.786143Z","end":"2026-05-21T19:34:36.486389Z","steps":["trace[994386210] 'agreement among raft nodes before linearized reading' (duration: 699.457483ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:34:36.486641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-21T19:34:35.786123Z","time spent":"700.491896ms","remote":"127.0.0.1:52906","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":29,"response size":29,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-05-21T19:38:23.491092Z","caller":"traceutil/trace.go:171","msg":"trace[1412759740] linearizableReadLoop","detail":"{readStateIndex:2543; appliedIndex:2542; }","duration":"216.377921ms","start":"2026-05-21T19:38:23.27469Z","end":"2026-05-21T19:38:23.491068Z","steps":["trace[1412759740] 'read index received' (duration: 215.45592ms)","trace[1412759740] 'applied index is now lower than readState.Index' (duration: 920.581µs)"],"step_count":2} {"level":"info","ts":"2026-05-21T19:38:23.491158Z","caller":"traceutil/trace.go:171","msg":"trace[447460419] transaction","detail":"{read_only:false; response_revision:2438; number_of_response:1; }","duration":"231.476315ms","start":"2026-05-21T19:38:23.259659Z","end":"2026-05-21T19:38:23.491136Z","steps":["trace[447460419] 'process raft request' (duration: 230.452942ms)"],"step_count":1} {"level":"warn","ts":"2026-05-21T19:38:23.491225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.509244ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-21T19:38:23.491257Z","caller":"traceutil/trace.go:171","msg":"trace[1992386174] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:2438; }","duration":"216.602196ms","start":"2026-05-21T19:38:23.274646Z","end":"2026-05-21T19:38:23.491248Z","steps":["trace[1992386174] 'agreement among raft nodes before linearized reading' (duration: 216.520004ms)"],"step_count":1}