{"level":"warn","ts":"2026-04-22T16:26:15.424336Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:26:15.424426Z","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-04-22T16:26:15.424498Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-22T16:26:15.424511Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.180:2380"]} {"level":"info","ts":"2026-04-22T16:26:15.424558Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:26:15.42569Z","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-04-22T16:26:15.425765Z","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-04-22T16:26:15.437523Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"11.554976ms"} {"level":"info","ts":"2026-04-22T16:26:15.458042Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5d6864bfaf2cb565","cluster-id":"32ff95319ee9cd99"} {"level":"info","ts":"2026-04-22T16:26:15.458121Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=()"} {"level":"info","ts":"2026-04-22T16:26:15.458141Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 0"} {"level":"info","ts":"2026-04-22T16:26:15.458152Z","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-04-22T16:26:15.458159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became follower at term 1"} {"level":"info","ts":"2026-04-22T16:26:15.458191Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"warn","ts":"2026-04-22T16:26:15.477631Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-22T16:26:15.480185Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-22T16:26:15.482365Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-22T16:26:15.484856Z","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-04-22T16:26:15.485032Z","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-04-22T16:26:15.485159Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:26:15.485207Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:26:15.485217Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-22T16:26:15.485817Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 switched to configuration voters=(6730740417545680229)"} {"level":"info","ts":"2026-04-22T16:26:15.486161Z","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-04-22T16:26:15.489317Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-22T16:26:15.489405Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-04-22T16:26:15.489438Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.180:2380"} {"level":"info","ts":"2026-04-22T16:26:15.489595Z","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-04-22T16:26:15.489628Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-22T16:26:16.159046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 is starting a new election at term 1"} {"level":"info","ts":"2026-04-22T16:26:16.159139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-22T16:26:16.159168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgPreVoteResp from 5d6864bfaf2cb565 at term 1"} {"level":"info","ts":"2026-04-22T16:26:16.159194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became candidate at term 2"} {"level":"info","ts":"2026-04-22T16:26:16.159205Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 received MsgVoteResp from 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-04-22T16:26:16.159226Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5d6864bfaf2cb565 became leader at term 2"} {"level":"info","ts":"2026-04-22T16:26:16.15924Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5d6864bfaf2cb565 elected leader 5d6864bfaf2cb565 at term 2"} {"level":"info","ts":"2026-04-22T16:26:16.160264Z","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-04-22T16:26:16.160436Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:26:16.160492Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-22T16:26:16.160617Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:26:16.162462Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-22T16:26:16.162523Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-22T16:26:16.164173Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.180:2379"} {"level":"info","ts":"2026-04-22T16:26:16.164867Z","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-04-22T16:26:16.164955Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-22T16:26:16.164968Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:26:16.16501Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-22T16:26:17.624053Z","caller":"traceutil/trace.go:171","msg":"trace[495803119] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"241.282591ms","start":"2026-04-22T16:26:17.382746Z","end":"2026-04-22T16:26:17.624029Z","steps":["trace[495803119] 'process raft request' (duration: 241.055166ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.830424Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.173705896s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:26:18.831036Z","caller":"traceutil/trace.go:171","msg":"trace[1536131] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"1.447583437s","start":"2026-04-22T16:26:17.383428Z","end":"2026-04-22T16:26:18.831012Z","steps":["trace[1536131] 'read index received' (duration: 240.466193ms)","trace[1536131] 'applied index is now lower than readState.Index' (duration: 1.207116164s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:18.83113Z","caller":"traceutil/trace.go:171","msg":"trace[1471466596] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.380520585s","start":"2026-04-22T16:26:17.4506Z","end":"2026-04-22T16:26:18.83112Z","steps":["trace[1471466596] 'process raft request' (duration: 1.380332821s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831098Z","caller":"traceutil/trace.go:171","msg":"trace[1323471938] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"1.380464603s","start":"2026-04-22T16:26:17.450593Z","end":"2026-04-22T16:26:18.831058Z","steps":["trace[1323471938] 'process raft request' (duration: 1.38031435s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831291Z","caller":"traceutil/trace.go:171","msg":"trace[389048439] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"1.444101066s","start":"2026-04-22T16:26:17.387165Z","end":"2026-04-22T16:26:18.831266Z","steps":["trace[389048439] 'process raft request' (duration: 1.443597435s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831156Z","caller":"traceutil/trace.go:171","msg":"trace[1956716368] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"1.380650108s","start":"2026-04-22T16:26:17.450467Z","end":"2026-04-22T16:26:18.831117Z","steps":["trace[1956716368] 'process raft request' (duration: 1.38032878s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831336Z","caller":"traceutil/trace.go:171","msg":"trace[1455233021] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"1.38078547s","start":"2026-04-22T16:26:17.450535Z","end":"2026-04-22T16:26:18.83132Z","steps":["trace[1455233021] 'process raft request' (duration: 1.380286759s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831381Z","caller":"traceutil/trace.go:171","msg":"trace[283624271] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"1.38079861s","start":"2026-04-22T16:26:17.450572Z","end":"2026-04-22T16:26:18.831371Z","steps":["trace[283624271] 'process raft request' (duration: 1.380284079s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831397Z","caller":"traceutil/trace.go:171","msg":"trace[1058644451] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"1.380817841s","start":"2026-04-22T16:26:17.450568Z","end":"2026-04-22T16:26:18.831385Z","steps":["trace[1058644451] 'process raft request' (duration: 1.380317099s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831441Z","caller":"traceutil/trace.go:171","msg":"trace[1552251844] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.363968954s","start":"2026-04-22T16:26:17.467458Z","end":"2026-04-22T16:26:18.831427Z","steps":["trace[1552251844] 'process raft request' (duration: 1.363517924s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831454Z","caller":"traceutil/trace.go:171","msg":"trace[638334438] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"1.448233222s","start":"2026-04-22T16:26:17.383164Z","end":"2026-04-22T16:26:18.831397Z","steps":["trace[638334438] 'process raft request' (duration: 1.447468705s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.448061098s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"warn","ts":"2026-04-22T16:26:18.831654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398856085s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:18.831708Z","caller":"traceutil/trace.go:171","msg":"trace[1643077737] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"1.448228422s","start":"2026-04-22T16:26:17.383409Z","end":"2026-04-22T16:26:18.831638Z","steps":["trace[1643077737] 'agreement among raft nodes before linearized reading' (duration: 1.447942755s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:18.831721Z","caller":"traceutil/trace.go:171","msg":"trace[1065907474] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.398908527s","start":"2026-04-22T16:26:17.43278Z","end":"2026-04-22T16:26:18.831688Z","steps":["trace[1065907474] 'agreement among raft nodes before linearized reading' (duration: 1.398829015s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.83172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.383139254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.180\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:18.831742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.467444Z","time spent":"1.364023705s","remote":"127.0.0.1:38766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.831723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.45056Z","time spent":"1.380735659s","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.831761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.43276Z","time spent":"1.398991479s","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-22T16:26:18.831768Z","caller":"traceutil/trace.go:171","msg":"trace[1710252531] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:0; response_revision:11; }","duration":"1.383227496s","start":"2026-04-22T16:26:17.448526Z","end":"2026-04-22T16:26:18.831753Z","steps":["trace[1710252531] 'agreement among raft nodes before linearized reading' (duration: 1.383117124s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.448511Z","time spent":"1.383290837s","remote":"127.0.0.1:38618","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":26,"request content":"key:\"/registry/masterleases/199.19.213.180\" "} {"level":"warn","ts":"2026-04-22T16:26:18.831823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.69594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:18.831866Z","caller":"traceutil/trace.go:171","msg":"trace[1295296087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"287.76461ms","start":"2026-04-22T16:26:18.544087Z","end":"2026-04-22T16:26:18.831851Z","steps":["trace[1295296087] 'agreement among raft nodes before linearized reading' (duration: 287.705799ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.383147Z","time spent":"1.448365615s","remote":"127.0.0.1:38658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:18.831929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.381273762s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:18.831979Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.387158Z","time spent":"1.444188059s","remote":"127.0.0.1:38820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:18.831991Z","caller":"traceutil/trace.go:171","msg":"trace[1428523484] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.381524517s","start":"2026-04-22T16:26:17.450448Z","end":"2026-04-22T16:26:18.831972Z","steps":["trace[1428523484] 'agreement among raft nodes before linearized reading' (duration: 1.381454345s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.39904243s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:18.832022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450442Z","time spent":"1.381573328s","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-22T16:26:18.832048Z","caller":"traceutil/trace.go:171","msg":"trace[1159756453] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.399110681s","start":"2026-04-22T16:26:17.432914Z","end":"2026-04-22T16:26:18.832025Z","steps":["trace[1159756453] 'agreement among raft nodes before linearized reading' (duration: 1.399000029s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.398335074s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:18.832099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450549Z","time spent":"1.380911964s","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.832108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450511Z","time spent":"1.380863403s","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:18.83212Z","caller":"traceutil/trace.go:171","msg":"trace[323889473] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.39859612s","start":"2026-04-22T16:26:17.433512Z","end":"2026-04-22T16:26:18.832108Z","steps":["trace[323889473] 'agreement among raft nodes before linearized reading' (duration: 1.398396836s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.832137Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.4329Z","time spent":"1.399185063s","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T16:26:18.832155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.433487Z","time spent":"1.398659681s","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-22T16:26:18.831678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450587Z","time spent":"1.380580696s","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.832261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450446Z","time spent":"1.380913923s","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.832283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.957353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:18.832308Z","caller":"traceutil/trace.go:171","msg":"trace[98369776] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:11; }","duration":"483.008894ms","start":"2026-04-22T16:26:18.349291Z","end":"2026-04-22T16:26:18.8323Z","steps":["trace[98369776] 'agreement among raft nodes before linearized reading' (duration: 482.969724ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.832336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450563Z","time spent":"1.380839631s","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:18.832335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.349272Z","time spent":"483.056135ms","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":26,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"warn","ts":"2026-04-22T16:26:18.831751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.383401Z","time spent":"1.448339915s","remote":"127.0.0.1:38766","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-04-22T16:26:18.832525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.85008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:18.832564Z","caller":"traceutil/trace.go:171","msg":"trace[725245777] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:11; }","duration":"482.917152ms","start":"2026-04-22T16:26:18.349635Z","end":"2026-04-22T16:26:18.832552Z","steps":["trace[725245777] 'agreement among raft nodes before linearized reading' (duration: 482.865871ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.831755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.380915133s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:18.832593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.349604Z","time spent":"482.981114ms","remote":"127.0.0.1:38920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":26,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-04-22T16:26:18.832601Z","caller":"traceutil/trace.go:171","msg":"trace[435053867] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"1.381829355s","start":"2026-04-22T16:26:17.450759Z","end":"2026-04-22T16:26:18.832588Z","steps":["trace[435053867] 'agreement among raft nodes before linearized reading' (duration: 1.380876522s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.832827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.450745Z","time spent":"1.382069s","remote":"127.0.0.1:38674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":26,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-22T16:26:18.832649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.330574565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:18.832889Z","caller":"traceutil/trace.go:171","msg":"trace[2056185689] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"1.3308038s","start":"2026-04-22T16:26:17.502067Z","end":"2026-04-22T16:26:18.832871Z","steps":["trace[2056185689] 'agreement among raft nodes before linearized reading' (duration: 1.330559275s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:18.832946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:17.502031Z","time spent":"1.330898302s","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":26,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-22T16:26:19.222574Z","caller":"traceutil/trace.go:171","msg":"trace[307676029] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"384.926437ms","start":"2026-04-22T16:26:18.837623Z","end":"2026-04-22T16:26:19.22255Z","steps":["trace[307676029] 'process raft request' (duration: 375.376607ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.222703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.8376Z","time spent":"385.040149ms","remote":"127.0.0.1:39026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.225922Z","caller":"traceutil/trace.go:171","msg":"trace[245585614] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"388.090699ms","start":"2026-04-22T16:26:18.837807Z","end":"2026-04-22T16:26:19.225897Z","steps":["trace[245585614] 'process raft request' (duration: 387.893005ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:19.226095Z","caller":"traceutil/trace.go:171","msg":"trace[2098199897] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"387.558327ms","start":"2026-04-22T16:26:18.838512Z","end":"2026-04-22T16:26:19.22607Z","steps":["trace[2098199897] 'process raft request' (duration: 387.291951ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.226139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.837785Z","time spent":"388.300653ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:19.226217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.838503Z","time spent":"387.655129ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.226254Z","caller":"traceutil/trace.go:171","msg":"trace[123626821] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"386.796019ms","start":"2026-04-22T16:26:18.839426Z","end":"2026-04-22T16:26:19.226222Z","steps":["trace[123626821] 'process raft request' (duration: 386.400891ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.226464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.839392Z","time spent":"386.955623ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":338,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.226772Z","caller":"traceutil/trace.go:171","msg":"trace[1692305407] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"386.866302ms","start":"2026-04-22T16:26:18.839892Z","end":"2026-04-22T16:26:19.226758Z","steps":["trace[1692305407] 'process raft request' (duration: 386.668737ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.226851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.839882Z","time spent":"386.942553ms","remote":"127.0.0.1:38976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.226938Z","caller":"traceutil/trace.go:171","msg":"trace[354052822] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"386.347508ms","start":"2026-04-22T16:26:18.840575Z","end":"2026-04-22T16:26:19.226923Z","steps":["trace[354052822] 'process raft request' (duration: 386.145824ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:19.226975Z","caller":"traceutil/trace.go:171","msg":"trace[1874497397] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"386.978673ms","start":"2026-04-22T16:26:18.839984Z","end":"2026-04-22T16:26:19.226963Z","steps":["trace[1874497397] 'process raft request' (duration: 386.666486ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.227036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.839976Z","time spent":"387.029655ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:19.227042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.840557Z","time spent":"386.419781ms","remote":"127.0.0.1:38944","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":429,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.227279Z","caller":"traceutil/trace.go:171","msg":"trace[1635594820] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"386.576674ms","start":"2026-04-22T16:26:18.840692Z","end":"2026-04-22T16:26:19.227269Z","steps":["trace[1635594820] 'process raft request' (duration: 386.162114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.22734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.840675Z","time spent":"386.636406ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.227672Z","caller":"traceutil/trace.go:171","msg":"trace[624152382] linearizableReadLoop","detail":"{readStateIndex:16; appliedIndex:14; }","duration":"389.377779ms","start":"2026-04-22T16:26:18.838282Z","end":"2026-04-22T16:26:19.22766Z","steps":["trace[624152382] 'read index received' (duration: 374.780452ms)","trace[624152382] 'applied index is now lower than readState.Index' (duration: 14.596427ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:19.227741Z","caller":"traceutil/trace.go:171","msg":"trace[1442728982] transaction","detail":"{read_only:false; response_revision:20; number_of_response:1; }","duration":"386.593535ms","start":"2026-04-22T16:26:18.841121Z","end":"2026-04-22T16:26:19.227714Z","steps":["trace[1442728982] 'process raft request' (duration: 386.091894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.227844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.841114Z","time spent":"386.687487ms","remote":"127.0.0.1:39104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":36,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:19.227876Z","caller":"traceutil/trace.go:171","msg":"trace[406072551] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"382.522312ms","start":"2026-04-22T16:26:18.845342Z","end":"2026-04-22T16:26:19.227864Z","steps":["trace[406072551] 'process raft request' (duration: 382.256126ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.227953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.845334Z","time spent":"382.578013ms","remote":"127.0.0.1:38766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3479,"response count":0,"response size":36,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:19.228106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.820529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-7cb7k\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-04-22T16:26:19.228145Z","caller":"traceutil/trace.go:171","msg":"trace[19552935] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-7cb7k; range_end:; response_count:1; response_revision:21; }","duration":"389.876571ms","start":"2026-04-22T16:26:18.838259Z","end":"2026-04-22T16:26:19.228136Z","steps":["trace[19552935] 'agreement among raft nodes before linearized reading' (duration: 389.804099ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.228178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.83825Z","time spent":"389.920662ms","remote":"127.0.0.1:38820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":883,"request content":"key:\"/registry/certificatesigningrequests/csr-7cb7k\" "} {"level":"info","ts":"2026-04-22T16:26:19.227677Z","caller":"traceutil/trace.go:171","msg":"trace[241024181] transaction","detail":"{read_only:false; number_of_response:0; response_revision:20; }","duration":"386.492463ms","start":"2026-04-22T16:26:18.84117Z","end":"2026-04-22T16:26:19.227663Z","steps":["trace[241024181] 'process raft request' (duration: 386.392341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.228295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.841164Z","time spent":"387.093377ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":26,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:19.23804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.675024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:19.238093Z","caller":"traceutil/trace.go:171","msg":"trace[669911976] range","detail":"{range_begin:/registry/leases/kube-system/apiserver-f3tcohoifagyom4bbi4wgeu7te; range_end:; response_count:0; response_revision:22; }","duration":"199.756376ms","start":"2026-04-22T16:26:19.038326Z","end":"2026-04-22T16:26:19.238082Z","steps":["trace[669911976] 'agreement among raft nodes before linearized reading' (duration: 199.654114ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.238138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.101516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-22T16:26:19.23817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.472068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-22T16:26:19.238188Z","caller":"traceutil/trace.go:171","msg":"trace[1024281420] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22; }","duration":"397.166057ms","start":"2026-04-22T16:26:18.841008Z","end":"2026-04-22T16:26:19.238174Z","steps":["trace[1024281420] 'agreement among raft nodes before linearized reading' (duration: 397.093716ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:19.238204Z","caller":"traceutil/trace.go:171","msg":"trace[2059524613] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:22; }","duration":"398.517479ms","start":"2026-04-22T16:26:18.839679Z","end":"2026-04-22T16:26:19.238196Z","steps":["trace[2059524613] 'agreement among raft nodes before linearized reading' (duration: 398.468008ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.23823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.839673Z","time spent":"398.551ms","remote":"127.0.0.1:38932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":26,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" "} {"level":"warn","ts":"2026-04-22T16:26:19.238285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"340.521174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"warn","ts":"2026-04-22T16:26:19.238234Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.841Z","time spent":"397.213638ms","remote":"127.0.0.1:38572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":26,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-22T16:26:19.238329Z","caller":"traceutil/trace.go:171","msg":"trace[1720672591] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:22; }","duration":"340.611426ms","start":"2026-04-22T16:26:18.897708Z","end":"2026-04-22T16:26:19.23832Z","steps":["trace[1720672591] 'agreement among raft nodes before linearized reading' (duration: 340.539225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:19.23835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:18.89768Z","time spent":"340.665948ms","remote":"127.0.0.1:38706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":372,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"info","ts":"2026-04-22T16:26:25.782048Z","caller":"traceutil/trace.go:171","msg":"trace[1514417157] transaction","detail":"{read_only:false; response_revision:295; number_of_response:1; }","duration":"103.369331ms","start":"2026-04-22T16:26:25.678654Z","end":"2026-04-22T16:26:25.782024Z","steps":["trace[1514417157] 'process raft request' (duration: 103.177777ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:25.788552Z","caller":"traceutil/trace.go:171","msg":"trace[1950774011] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"109.262434ms","start":"2026-04-22T16:26:25.679264Z","end":"2026-04-22T16:26:25.788527Z","steps":["trace[1950774011] 'process raft request' (duration: 109.029358ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:47.976561Z","caller":"traceutil/trace.go:171","msg":"trace[2085530701] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"898.67633ms","start":"2026-04-22T16:26:47.077843Z","end":"2026-04-22T16:26:47.97652Z","steps":["trace[2085530701] 'process raft request' (duration: 895.348995ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:47.976721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:47.077827Z","time spent":"898.819393ms","remote":"127.0.0.1:38634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-22T16:26:47.988244Z","caller":"traceutil/trace.go:171","msg":"trace[264031754] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:517; }","duration":"325.12901ms","start":"2026-04-22T16:26:47.663096Z","end":"2026-04-22T16:26:47.988226Z","steps":["trace[264031754] 'read index received' (duration: 310.110411ms)","trace[264031754] 'applied index is now lower than readState.Index' (duration: 15.017909ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:47.988561Z","caller":"traceutil/trace.go:171","msg":"trace[1635721441] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"908.329627ms","start":"2026-04-22T16:26:47.080215Z","end":"2026-04-22T16:26:47.988545Z","steps":["trace[1635721441] 'process raft request' (duration: 907.813105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:47.988578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.464727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:47.988643Z","caller":"traceutil/trace.go:171","msg":"trace[1326822014] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:498; }","duration":"325.578889ms","start":"2026-04-22T16:26:47.663045Z","end":"2026-04-22T16:26:47.988624Z","steps":["trace[1326822014] 'agreement among raft nodes before linearized reading' (duration: 325.440666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:47.98869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:47.663025Z","time spent":"325.659262ms","remote":"127.0.0.1:38570","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-04-22T16:26:47.988756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:47.080197Z","time spent":"908.415719ms","remote":"127.0.0.1:38634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:47.98886Z","caller":"traceutil/trace.go:171","msg":"trace[2141932856] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"908.580082ms","start":"2026-04-22T16:26:47.080271Z","end":"2026-04-22T16:26:47.988851Z","steps":["trace[2141932856] 'process raft request' (duration: 907.847526ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:47.988934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:47.080268Z","time spent":"908.617014ms","remote":"127.0.0.1:38634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23057,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:26:54.288375Z","caller":"traceutil/trace.go:171","msg":"trace[1786965629] linearizableReadLoop","detail":"{readStateIndex:599; appliedIndex:598; }","duration":"262.344943ms","start":"2026-04-22T16:26:54.026002Z","end":"2026-04-22T16:26:54.288347Z","steps":["trace[1786965629] 'read index received' (duration: 261.493984ms)","trace[1786965629] 'applied index is now lower than readState.Index' (duration: 849.399µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:54.288546Z","caller":"traceutil/trace.go:171","msg":"trace[916043865] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"341.663262ms","start":"2026-04-22T16:26:53.946873Z","end":"2026-04-22T16:26:54.288536Z","steps":["trace[916043865] 'process raft request' (duration: 340.859385ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:54.28865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:53.946775Z","time spent":"341.798535ms","remote":"127.0.0.1:38680","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:26:54.288737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.370404ms","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-04-22T16:26:54.288847Z","caller":"traceutil/trace.go:171","msg":"trace[1352780043] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:576; }","duration":"262.511997ms","start":"2026-04-22T16:26:54.026308Z","end":"2026-04-22T16:26:54.28882Z","steps":["trace[1352780043] 'agreement among raft nodes before linearized reading' (duration: 262.261911ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:54.28909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.772855ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T16:26:54.289158Z","caller":"traceutil/trace.go:171","msg":"trace[966615248] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:576; }","duration":"235.884069ms","start":"2026-04-22T16:26:54.053256Z","end":"2026-04-22T16:26:54.28914Z","steps":["trace[966615248] 'agreement among raft nodes before linearized reading' (duration: 235.761595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:54.289321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.281455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:26:54.289467Z","caller":"traceutil/trace.go:171","msg":"trace[1651129075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:576; }","duration":"263.477879ms","start":"2026-04-22T16:26:54.025957Z","end":"2026-04-22T16:26:54.289435Z","steps":["trace[1651129075] 'agreement among raft nodes before linearized reading' (duration: 263.036599ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:54.870931Z","caller":"traceutil/trace.go:171","msg":"trace[1974912594] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"207.275213ms","start":"2026-04-22T16:26:54.663621Z","end":"2026-04-22T16:26:54.870896Z","steps":["trace[1974912594] 'read index received' (duration: 191.804305ms)","trace[1974912594] 'applied index is now lower than readState.Index' (duration: 15.469708ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:54.871023Z","caller":"traceutil/trace.go:171","msg":"trace[1098101251] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"523.88241ms","start":"2026-04-22T16:26:54.347063Z","end":"2026-04-22T16:26:54.870946Z","steps":["trace[1098101251] 'process raft request' (duration: 508.446953ms)","trace[1098101251] 'compare' (duration: 14.991587ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:54.871139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.508188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:54.871222Z","caller":"traceutil/trace.go:171","msg":"trace[374343940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"207.60233ms","start":"2026-04-22T16:26:54.663601Z","end":"2026-04-22T16:26:54.871203Z","steps":["trace[374343940] 'agreement among raft nodes before linearized reading' (duration: 207.431696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:54.871252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:54.347043Z","time spent":"524.105084ms","remote":"127.0.0.1:38808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:54.871285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.668281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gb54j\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-04-22T16:26:54.871347Z","caller":"traceutil/trace.go:171","msg":"trace[187544594] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gb54j; range_end:; response_count:1; response_revision:580; }","duration":"152.777714ms","start":"2026-04-22T16:26:54.718556Z","end":"2026-04-22T16:26:54.871333Z","steps":["trace[187544594] 'agreement among raft nodes before linearized reading' (duration: 152.514768ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:55.025639Z","caller":"traceutil/trace.go:171","msg":"trace[2134368546] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"139.440502ms","start":"2026-04-22T16:26:54.886159Z","end":"2026-04-22T16:26:55.025599Z","steps":["trace[2134368546] 'process raft request' (duration: 134.714797ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.458032Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.437016365s","expected-duration":"1s"} {"level":"info","ts":"2026-04-22T16:26:56.458316Z","caller":"traceutil/trace.go:171","msg":"trace[1057792343] linearizableReadLoop","detail":"{readStateIndex:605; appliedIndex:603; }","duration":"1.503905473s","start":"2026-04-22T16:26:54.954391Z","end":"2026-04-22T16:26:56.458296Z","steps":["trace[1057792343] 'read index received' (duration: 66.640132ms)","trace[1057792343] 'applied index is now lower than readState.Index' (duration: 1.437264591s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:26:56.458398Z","caller":"traceutil/trace.go:171","msg":"trace[1987121852] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"1.570381912s","start":"2026-04-22T16:26:54.888011Z","end":"2026-04-22T16:26:56.458393Z","steps":["trace[1987121852] 'process raft request' (duration: 1.570182418s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.458459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:54.887993Z","time spent":"1.570421404s","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-22T16:26:56.458591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.50419414s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T16:26:56.458645Z","caller":"traceutil/trace.go:171","msg":"trace[181253892] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:582; }","duration":"1.504314442s","start":"2026-04-22T16:26:54.954314Z","end":"2026-04-22T16:26:56.458629Z","steps":["trace[181253892] 'agreement among raft nodes before linearized reading' (duration: 1.504094947s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.458682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:54.954208Z","time spent":"1.504466156s","remote":"127.0.0.1:38822","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-04-22T16:26:56.616216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.007233ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-22T16:26:56.616357Z","caller":"traceutil/trace.go:171","msg":"trace[876781434] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"157.96484ms","start":"2026-04-22T16:26:56.458369Z","end":"2026-04-22T16:26:56.616334Z","steps":["trace[876781434] 'read index received' (duration: 34.772013ms)","trace[876781434] 'applied index is now lower than readState.Index' (duration: 123.190947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:26:56.616501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.46355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.616586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.99606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.616639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.470792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.616695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.535063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.616665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.605421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.616674Z","caller":"traceutil/trace.go:171","msg":"trace[1258399053] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:582; }","duration":"409.112473ms","start":"2026-04-22T16:26:56.207549Z","end":"2026-04-22T16:26:56.616661Z","steps":["trace[1258399053] 'agreement among raft nodes before linearized reading' (duration: 408.98202ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.616764Z","caller":"traceutil/trace.go:171","msg":"trace[1600223919] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:582; }","duration":"408.769266ms","start":"2026-04-22T16:26:56.207977Z","end":"2026-04-22T16:26:56.616746Z","steps":["trace[1600223919] 'agreement among raft nodes before linearized reading' (duration: 408.585971ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.616736Z","caller":"traceutil/trace.go:171","msg":"trace[56113465] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:582; }","duration":"433.616404ms","start":"2026-04-22T16:26:56.18311Z","end":"2026-04-22T16:26:56.616727Z","steps":["trace[56113465] 'agreement among raft nodes before linearized reading' (duration: 433.527682ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.616696Z","caller":"traceutil/trace.go:171","msg":"trace[904270688] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:582; }","duration":"433.544553ms","start":"2026-04-22T16:26:56.183136Z","end":"2026-04-22T16:26:56.61668Z","steps":["trace[904270688] 'agreement among raft nodes before linearized reading' (duration: 433.452651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.616838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.207542Z","time spent":"409.282156ms","remote":"127.0.0.1:60736","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/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:26:56.616844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.207966Z","time spent":"408.862057ms","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:500 "} {"level":"warn","ts":"2026-04-22T16:26:56.616865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.183132Z","time spent":"433.711567ms","remote":"127.0.0.1:60732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:26:56.616897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.488762ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.616916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.843102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-22T16:26:56.616949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.351068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.616978Z","caller":"traceutil/trace.go:171","msg":"trace[273666007] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:582; }","duration":"260.612195ms","start":"2026-04-22T16:26:56.356348Z","end":"2026-04-22T16:26:56.616961Z","steps":["trace[273666007] 'agreement among raft nodes before linearized reading' (duration: 260.488643ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.617023Z","caller":"traceutil/trace.go:171","msg":"trace[1293756763] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tcproutes/; range_end:/registry/gateway.networking.k8s.io/tcproutes0; response_count:0; response_revision:582; }","duration":"409.44526ms","start":"2026-04-22T16:26:56.207568Z","end":"2026-04-22T16:26:56.617013Z","steps":["trace[1293756763] 'agreement among raft nodes before linearized reading' (duration: 409.331218ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.617047Z","caller":"traceutil/trace.go:171","msg":"trace[1530678769] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:582; }","duration":"266.010556ms","start":"2026-04-22T16:26:56.350994Z","end":"2026-04-22T16:26:56.617004Z","steps":["trace[1530678769] 'agreement among raft nodes before linearized reading' (duration: 265.458894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.850192ms","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-04-22T16:26:56.617107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.207557Z","time spent":"409.516742ms","remote":"127.0.0.1:60736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tcproutes/\" range_end:\"/registry/gateway.networking.k8s.io/tcproutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T16:26:56.617099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.023457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:26:56.617166Z","caller":"traceutil/trace.go:171","msg":"trace[1808726525] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:582; }","duration":"265.988406ms","start":"2026-04-22T16:26:56.351158Z","end":"2026-04-22T16:26:56.617147Z","steps":["trace[1808726525] 'agreement among raft nodes before linearized reading' (duration: 265.76039ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.617177Z","caller":"traceutil/trace.go:171","msg":"trace[1363102149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:582; }","duration":"266.12636ms","start":"2026-04-22T16:26:56.351028Z","end":"2026-04-22T16:26:56.617155Z","steps":["trace[1363102149] 'agreement among raft nodes before linearized reading' (duration: 265.977255ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.616872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.183104Z","time spent":"433.752908ms","remote":"127.0.0.1:60732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T16:26:56.616774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.506653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617277Z","caller":"traceutil/trace.go:171","msg":"trace[1193992818] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:582; }","duration":"261.036916ms","start":"2026-04-22T16:26:56.356229Z","end":"2026-04-22T16:26:56.617266Z","steps":["trace[1193992818] 'agreement among raft nodes before linearized reading' (duration: 260.487973ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.170785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617389Z","caller":"traceutil/trace.go:171","msg":"trace[1931127386] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:582; }","duration":"433.414371ms","start":"2026-04-22T16:26:56.183955Z","end":"2026-04-22T16:26:56.61737Z","steps":["trace[1931127386] 'agreement among raft nodes before linearized reading' (duration: 433.152696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.7449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617498Z","caller":"traceutil/trace.go:171","msg":"trace[1700489525] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:582; }","duration":"591.793332ms","start":"2026-04-22T16:26:56.025696Z","end":"2026-04-22T16:26:56.61749Z","steps":["trace[1700489525] 'agreement among raft nodes before linearized reading' (duration: 591.746141ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.183925Z","time spent":"433.523363ms","remote":"127.0.0.1:60732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" limit:500 "} {"level":"warn","ts":"2026-04-22T16:26:56.617517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.025689Z","time spent":"591.821802ms","remote":"127.0.0.1:60682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:500 "} {"level":"warn","ts":"2026-04-22T16:26:56.617561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.086232ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.617614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.472977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617648Z","caller":"traceutil/trace.go:171","msg":"trace[1757470511] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:582; }","duration":"592.544348ms","start":"2026-04-22T16:26:56.025095Z","end":"2026-04-22T16:26:56.617639Z","steps":["trace[1757470511] 'agreement among raft nodes before linearized reading' (duration: 592.489977ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.61762Z","caller":"traceutil/trace.go:171","msg":"trace[1475741449] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:582; }","duration":"566.238305ms","start":"2026-04-22T16:26:56.051369Z","end":"2026-04-22T16:26:56.617608Z","steps":["trace[1475741449] 'agreement among raft nodes before linearized reading' (duration: 566.062341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.025074Z","time spent":"592.592649ms","remote":"127.0.0.1:60682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T16:26:56.617692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.810468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.617662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.809628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617717Z","caller":"traceutil/trace.go:171","msg":"trace[34143517] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:582; }","duration":"566.87755ms","start":"2026-04-22T16:26:56.050833Z","end":"2026-04-22T16:26:56.617711Z","steps":["trace[34143517] 'agreement among raft nodes before linearized reading' (duration: 566.840219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.050816Z","time spent":"566.919241ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T16:26:56.617709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.522508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617735Z","caller":"traceutil/trace.go:171","msg":"trace[1885409506] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:582; }","duration":"566.909651ms","start":"2026-04-22T16:26:56.05081Z","end":"2026-04-22T16:26:56.61772Z","steps":["trace[1885409506] 'agreement among raft nodes before linearized reading' (duration: 566.799189ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.617775Z","caller":"traceutil/trace.go:171","msg":"trace[1827591738] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:582; }","duration":"592.61379ms","start":"2026-04-22T16:26:56.02515Z","end":"2026-04-22T16:26:56.617763Z","steps":["trace[1827591738] 'agreement among raft nodes before linearized reading' (duration: 592.521988ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.050791Z","time spent":"566.981692ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:26:56.617792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.051358Z","time spent":"566.369998ms","remote":"127.0.0.1:60690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" limit:500 "} {"level":"warn","ts":"2026-04-22T16:26:56.61784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.025144Z","time spent":"592.660721ms","remote":"127.0.0.1:60682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:26:56.617886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.477194ms","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":"warn","ts":"2026-04-22T16:26:56.617898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.261093ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.617916Z","caller":"traceutil/trace.go:171","msg":"trace[1389169673] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:582; }","duration":"508.530194ms","start":"2026-04-22T16:26:56.109377Z","end":"2026-04-22T16:26:56.617907Z","steps":["trace[1389169673] 'agreement among raft nodes before linearized reading' (duration: 508.489023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.506552ms","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":"warn","ts":"2026-04-22T16:26:56.617936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.109372Z","time spent":"508.558895ms","remote":"127.0.0.1:60710","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":"info","ts":"2026-04-22T16:26:56.617932Z","caller":"traceutil/trace.go:171","msg":"trace[381893556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:582; }","duration":"954.333275ms","start":"2026-04-22T16:26:55.663587Z","end":"2026-04-22T16:26:56.617921Z","steps":["trace[381893556] 'agreement among raft nodes before linearized reading' (duration: 954.277283ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.617952Z","caller":"traceutil/trace.go:171","msg":"trace[532265410] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:582; }","duration":"535.536753ms","start":"2026-04-22T16:26:56.08241Z","end":"2026-04-22T16:26:56.617947Z","steps":["trace[532265410] 'agreement among raft nodes before linearized reading' (duration: 535.508593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.617971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.082405Z","time spent":"535.562484ms","remote":"127.0.0.1:60706","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-04-22T16:26:56.617967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:55.663565Z","time spent":"954.394195ms","remote":"127.0.0.1:38570","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-04-22T16:26:56.618038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"458.817483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:26:56.618049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.814591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.618062Z","caller":"traceutil/trace.go:171","msg":"trace[924563387] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:582; }","duration":"508.841612ms","start":"2026-04-22T16:26:56.109216Z","end":"2026-04-22T16:26:56.618058Z","steps":["trace[924563387] 'agreement among raft nodes before linearized reading' (duration: 508.818281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.618075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.109209Z","time spent":"508.862662ms","remote":"127.0.0.1:60710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-22T16:26:56.618124Z","caller":"traceutil/trace.go:171","msg":"trace[250885504] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"458.840874ms","start":"2026-04-22T16:26:56.159214Z","end":"2026-04-22T16:26:56.618055Z","steps":["trace[250885504] 'agreement among raft nodes before linearized reading' (duration: 458.806913ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.618155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.159185Z","time spent":"458.963017ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:26:56.618268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.776427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.618288Z","caller":"traceutil/trace.go:171","msg":"trace[1541209837] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:582; }","duration":"507.811088ms","start":"2026-04-22T16:26:56.110473Z","end":"2026-04-22T16:26:56.618284Z","steps":["trace[1541209837] 'agreement among raft nodes before linearized reading' (duration: 507.780987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.618302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.110467Z","time spent":"507.831988ms","remote":"127.0.0.1:60710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 "} {"level":"warn","ts":"2026-04-22T16:26:56.61832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.139886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.616609Z","caller":"traceutil/trace.go:171","msg":"trace[549701832] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"456.556232ms","start":"2026-04-22T16:26:56.160007Z","end":"2026-04-22T16:26:56.616563Z","steps":["trace[549701832] 'agreement among raft nodes before linearized reading' (duration: 456.369038ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.618412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.159984Z","time spent":"458.416204ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:500 "} {"level":"info","ts":"2026-04-22T16:26:56.61855Z","caller":"traceutil/trace.go:171","msg":"trace[1392848909] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:582; }","duration":"536.388322ms","start":"2026-04-22T16:26:56.082143Z","end":"2026-04-22T16:26:56.618532Z","steps":["trace[1392848909] 'agreement among raft nodes before linearized reading' (duration: 536.111536ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.618626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.082131Z","time spent":"536.473644ms","remote":"127.0.0.1:60706","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 "} {"level":"warn","ts":"2026-04-22T16:26:56.62052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.302789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.620589Z","caller":"traceutil/trace.go:171","msg":"trace[325907815] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:582; }","duration":"461.33449ms","start":"2026-04-22T16:26:56.159236Z","end":"2026-04-22T16:26:56.62057Z","steps":["trace[325907815] 'agreement among raft nodes before linearized reading' (duration: 457.280768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.62065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:56.159229Z","time spent":"461.402102ms","remote":"127.0.0.1:60724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":101,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" limit:10000 "} {"level":"info","ts":"2026-04-22T16:26:56.620779Z","caller":"traceutil/trace.go:171","msg":"trace[177405015] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"157.062901ms","start":"2026-04-22T16:26:56.4637Z","end":"2026-04-22T16:26:56.620763Z","steps":["trace[177405015] 'process raft request' (duration: 156.826755ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.620951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.014711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.621025Z","caller":"traceutil/trace.go:171","msg":"trace[1864654225] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"138.126583ms","start":"2026-04-22T16:26:56.482881Z","end":"2026-04-22T16:26:56.621008Z","steps":["trace[1864654225] 'agreement among raft nodes before linearized reading' (duration: 138.00804ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.621067Z","caller":"traceutil/trace.go:171","msg":"trace[114560662] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"156.60217ms","start":"2026-04-22T16:26:56.464457Z","end":"2026-04-22T16:26:56.62106Z","steps":["trace[114560662] 'process raft request' (duration: 156.195291ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.621123Z","caller":"traceutil/trace.go:171","msg":"trace[159835489] transaction","detail":"{read_only:false; response_revision:586; number_of_response:1; }","duration":"154.984303ms","start":"2026-04-22T16:26:56.466109Z","end":"2026-04-22T16:26:56.621093Z","steps":["trace[159835489] 'process raft request' (duration: 154.668036ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.621403Z","caller":"traceutil/trace.go:171","msg":"trace[714537062] transaction","detail":"{read_only:false; response_revision:587; number_of_response:1; }","duration":"154.580424ms","start":"2026-04-22T16:26:56.466806Z","end":"2026-04-22T16:26:56.621387Z","steps":["trace[714537062] 'process raft request' (duration: 154.019072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.621557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.42615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.620979Z","caller":"traceutil/trace.go:171","msg":"trace[1014132760] transaction","detail":"{read_only:false; response_revision:585; number_of_response:1; }","duration":"156.247132ms","start":"2026-04-22T16:26:56.464722Z","end":"2026-04-22T16:26:56.620969Z","steps":["trace[1014132760] 'process raft request' (duration: 156.001377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:56.621729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.025759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:56.622563Z","caller":"traceutil/trace.go:171","msg":"trace[38678450] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"137.876678ms","start":"2026-04-22T16:26:56.484677Z","end":"2026-04-22T16:26:56.622553Z","steps":["trace[38678450] 'agreement among raft nodes before linearized reading' (duration: 137.03529ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:26:56.621587Z","caller":"traceutil/trace.go:171","msg":"trace[1443776131] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:587; }","duration":"138.458211ms","start":"2026-04-22T16:26:56.483122Z","end":"2026-04-22T16:26:56.62158Z","steps":["trace[1443776131] 'agreement among raft nodes before linearized reading' (duration: 138.41596ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:58.203502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.668427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:26:58.203618Z","caller":"traceutil/trace.go:171","msg":"trace[1104769248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:0; response_revision:601; }","duration":"339.82816ms","start":"2026-04-22T16:26:57.863771Z","end":"2026-04-22T16:26:58.203599Z","steps":["trace[1104769248] 'range keys from in-memory index tree' (duration: 339.608416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:26:58.203669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:26:57.86376Z","time spent":"339.895881ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-22T16:27:02.528581Z","caller":"traceutil/trace.go:171","msg":"trace[2087677095] linearizableReadLoop","detail":"{readStateIndex:696; appliedIndex:695; }","duration":"108.787153ms","start":"2026-04-22T16:27:02.41955Z","end":"2026-04-22T16:27:02.528337Z","steps":["trace[2087677095] 'read index received' (duration: 107.187776ms)","trace[2087677095] 'applied index is now lower than readState.Index' (duration: 1.598167ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:02.528703Z","caller":"traceutil/trace.go:171","msg":"trace[1270424227] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"267.438069ms","start":"2026-04-22T16:27:02.261191Z","end":"2026-04-22T16:27:02.528629Z","steps":["trace[1270424227] 'process raft request' (duration: 265.72109ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:02.529232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.658002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-22T16:27:02.529298Z","caller":"traceutil/trace.go:171","msg":"trace[1207387442] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:671; }","duration":"109.781755ms","start":"2026-04-22T16:27:02.4195Z","end":"2026-04-22T16:27:02.529281Z","steps":["trace[1207387442] 'agreement among raft nodes before linearized reading' (duration: 109.154471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:11.156382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.448555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:27:11.156479Z","caller":"traceutil/trace.go:171","msg":"trace[1788062507] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"340.970697ms","start":"2026-04-22T16:27:10.815496Z","end":"2026-04-22T16:27:11.156467Z","steps":["trace[1788062507] 'read index received' (duration: 42.491µs)","trace[1788062507] 'applied index is now lower than readState.Index' (duration: 340.927246ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:11.156585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.09203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vsphs\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-22T16:27:11.156603Z","caller":"traceutil/trace.go:171","msg":"trace[1436125877] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vsphs; range_end:; response_count:1; response_revision:738; }","duration":"341.13362ms","start":"2026-04-22T16:27:10.815463Z","end":"2026-04-22T16:27:11.156596Z","steps":["trace[1436125877] 'agreement among raft nodes before linearized reading' (duration: 341.035278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:11.156624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:10.815442Z","time spent":"341.177522ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3987,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-vsphs\" "} {"level":"info","ts":"2026-04-22T16:27:11.15675Z","caller":"traceutil/trace.go:171","msg":"trace[867635943] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"450.488945ms","start":"2026-04-22T16:27:10.706253Z","end":"2026-04-22T16:27:11.156742Z","steps":["trace[867635943] 'process raft request' (duration: 46.622911ms)","trace[867635943] 'compare' (duration: 403.24264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:11.156785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:10.706232Z","time spent":"450.534017ms","remote":"127.0.0.1:38822","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-04-22T16:27:11.90351Z","caller":"traceutil/trace.go:171","msg":"trace[1031218169] linearizableReadLoop","detail":"{readStateIndex:767; appliedIndex:766; }","duration":"239.517039ms","start":"2026-04-22T16:27:11.663974Z","end":"2026-04-22T16:27:11.903491Z","steps":["trace[1031218169] 'read index received' (duration: 239.271884ms)","trace[1031218169] 'applied index is now lower than readState.Index' (duration: 244.285µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:11.903613Z","caller":"traceutil/trace.go:171","msg":"trace[297744778] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"741.867594ms","start":"2026-04-22T16:27:11.161728Z","end":"2026-04-22T16:27:11.903596Z","steps":["trace[297744778] 'process raft request' (duration: 741.631459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:11.903636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.645722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:27:11.903673Z","caller":"traceutil/trace.go:171","msg":"trace[1006018368] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:739; }","duration":"239.727234ms","start":"2026-04-22T16:27:11.663929Z","end":"2026-04-22T16:27:11.903656Z","steps":["trace[1006018368] 'agreement among raft nodes before linearized reading' (duration: 239.636622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:11.903737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:11.161708Z","time spent":"741.953677ms","remote":"127.0.0.1:38768","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:27:41.190454Z","caller":"traceutil/trace.go:171","msg":"trace[993250608] linearizableReadLoop","detail":"{readStateIndex:976; appliedIndex:975; }","duration":"133.499297ms","start":"2026-04-22T16:27:41.056929Z","end":"2026-04-22T16:27:41.190428Z","steps":["trace[993250608] 'read index received' (duration: 132.29258ms)","trace[993250608] 'applied index is now lower than readState.Index' (duration: 1.205517ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:41.190486Z","caller":"traceutil/trace.go:171","msg":"trace[1468919302] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"156.128513ms","start":"2026-04-22T16:27:41.034328Z","end":"2026-04-22T16:27:41.190457Z","steps":["trace[1468919302] 'process raft request' (duration: 154.974067ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:41.190558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.60695ms","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-04-22T16:27:41.190619Z","caller":"traceutil/trace.go:171","msg":"trace[1626123508] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:940; }","duration":"133.704521ms","start":"2026-04-22T16:27:41.056901Z","end":"2026-04-22T16:27:41.190606Z","steps":["trace[1626123508] 'agreement among raft nodes before linearized reading' (duration: 133.586679ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:27:46.697745Z","caller":"traceutil/trace.go:171","msg":"trace[1094273924] linearizableReadLoop","detail":"{readStateIndex:996; appliedIndex:995; }","duration":"115.85453ms","start":"2026-04-22T16:27:46.581867Z","end":"2026-04-22T16:27:46.697721Z","steps":["trace[1094273924] 'read index received' (duration: 115.654166ms)","trace[1094273924] 'applied index is now lower than readState.Index' (duration: 199.414µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:46.697863Z","caller":"traceutil/trace.go:171","msg":"trace[1046334104] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"211.600129ms","start":"2026-04-22T16:27:46.486239Z","end":"2026-04-22T16:27:46.697839Z","steps":["trace[1046334104] 'process raft request' (duration: 211.347894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:46.697933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.033034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:27:46.697997Z","caller":"traceutil/trace.go:171","msg":"trace[954951937] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:959; }","duration":"116.150726ms","start":"2026-04-22T16:27:46.581828Z","end":"2026-04-22T16:27:46.697979Z","steps":["trace[954951937] 'agreement among raft nodes before linearized reading' (duration: 116.036404ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:47.456945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13071026898580941316,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-22T16:27:47.625084Z","caller":"traceutil/trace.go:171","msg":"trace[200866223] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"720.094685ms","start":"2026-04-22T16:27:46.904967Z","end":"2026-04-22T16:27:47.625061Z","steps":["trace[200866223] 'process raft request' (duration: 719.956542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:47.625228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:46.904945Z","time spent":"720.206137ms","remote":"127.0.0.1:38822","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-04-22T16:27:48.655222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.407644ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:27:48.655332Z","caller":"traceutil/trace.go:171","msg":"trace[2083869243] linearizableReadLoop","detail":"{readStateIndex:998; appliedIndex:996; }","duration":"1.699220177s","start":"2026-04-22T16:27:46.956095Z","end":"2026-04-22T16:27:48.655315Z","steps":["trace[2083869243] 'read index received' (duration: 668.799381ms)","trace[2083869243] 'applied index is now lower than readState.Index' (duration: 1.030419396s)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:27:48.655406Z","caller":"traceutil/trace.go:171","msg":"trace[1141213849] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"1.446036938s","start":"2026-04-22T16:27:47.20934Z","end":"2026-04-22T16:27:48.655377Z","steps":["trace[1141213849] 'process raft request' (duration: 1.328343337s)","trace[1141213849] 'compare' (duration: 117.024436ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:27:48.655526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.699431482s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:27:48.655557Z","caller":"traceutil/trace.go:171","msg":"trace[1133774790] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:961; }","duration":"1.699494964s","start":"2026-04-22T16:27:46.956052Z","end":"2026-04-22T16:27:48.655547Z","steps":["trace[1133774790] 'agreement among raft nodes before linearized reading' (duration: 1.699362181s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:48.655554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:47.209323Z","time spent":"1.446149251s","remote":"127.0.0.1:38822","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-04-22T16:27:48.655588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:46.956035Z","time spent":"1.699545345s","remote":"127.0.0.1:38822","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-04-22T16:27:48.655787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695166569s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T16:27:48.655811Z","caller":"traceutil/trace.go:171","msg":"trace[1944801585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:961; }","duration":"1.69523019s","start":"2026-04-22T16:27:46.960574Z","end":"2026-04-22T16:27:48.655805Z","steps":["trace[1944801585] 'agreement among raft nodes before linearized reading' (duration: 1.695119408s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:48.655832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:46.960549Z","time spent":"1.695279161s","remote":"127.0.0.1:38822","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-04-22T16:27:48.655979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31657634s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:27:48.656011Z","caller":"traceutil/trace.go:171","msg":"trace[1601074549] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:961; }","duration":"1.316641822s","start":"2026-04-22T16:27:47.33936Z","end":"2026-04-22T16:27:48.656001Z","steps":["trace[1601074549] 'agreement among raft nodes before linearized reading' (duration: 1.316537999s)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:48.656037Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:47.339311Z","time spent":"1.316720613s","remote":"127.0.0.1:38822","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-04-22T16:27:48.656057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"994.438999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:27:48.656083Z","caller":"traceutil/trace.go:171","msg":"trace[1946932258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"994.495919ms","start":"2026-04-22T16:27:47.661577Z","end":"2026-04-22T16:27:48.656073Z","steps":["trace[1946932258] 'agreement among raft nodes before linearized reading' (duration: 994.462288ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:27:48.656116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:27:47.661558Z","time spent":"994.549811ms","remote":"127.0.0.1:38570","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-04-22T16:28:10.345129Z","caller":"traceutil/trace.go:171","msg":"trace[280540871] linearizableReadLoop","detail":"{readStateIndex:1073; appliedIndex:1072; }","duration":"298.709138ms","start":"2026-04-22T16:28:10.046394Z","end":"2026-04-22T16:28:10.345103Z","steps":["trace[280540871] 'read index received' (duration: 298.432892ms)","trace[280540871] 'applied index is now lower than readState.Index' (duration: 274.146µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:10.345358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.939133ms","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-04-22T16:28:10.345397Z","caller":"traceutil/trace.go:171","msg":"trace[2106882948] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1032; }","duration":"299.041005ms","start":"2026-04-22T16:28:10.046345Z","end":"2026-04-22T16:28:10.345386Z","steps":["trace[2106882948] 'agreement among raft nodes before linearized reading' (duration: 298.866482ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:10.345576Z","caller":"traceutil/trace.go:171","msg":"trace[150028122] transaction","detail":"{read_only:false; response_revision:1032; number_of_response:1; }","duration":"625.504352ms","start":"2026-04-22T16:28:09.720029Z","end":"2026-04-22T16:28:10.345533Z","steps":["trace[150028122] 'process raft request' (duration: 624.844667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:10.34581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:09.719987Z","time spent":"625.708565ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:11.230657Z","caller":"traceutil/trace.go:171","msg":"trace[1167334273] linearizableReadLoop","detail":"{readStateIndex:1076; appliedIndex:1075; }","duration":"353.161111ms","start":"2026-04-22T16:28:10.877461Z","end":"2026-04-22T16:28:11.230622Z","steps":["trace[1167334273] 'read index received' (duration: 352.979137ms)","trace[1167334273] 'applied index is now lower than readState.Index' (duration: 180.844µs)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:11.230854Z","caller":"traceutil/trace.go:171","msg":"trace[1046911127] transaction","detail":"{read_only:false; response_revision:1034; number_of_response:1; }","duration":"414.063927ms","start":"2026-04-22T16:28:10.816769Z","end":"2026-04-22T16:28:11.230833Z","steps":["trace[1046911127] 'process raft request' (duration: 413.663158ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.230986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.417642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T16:28:11.230994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:10.816734Z","time spent":"414.181909ms","remote":"127.0.0.1:38822","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-04-22T16:28:11.231029Z","caller":"traceutil/trace.go:171","msg":"trace[527628201] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1034; }","duration":"351.512655ms","start":"2026-04-22T16:28:10.879506Z","end":"2026-04-22T16:28:11.231018Z","steps":["trace[527628201] 'agreement among raft nodes before linearized reading' (duration: 351.268529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.231062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:10.879483Z","time spent":"351.571845ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:28:11.231189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.671192ms","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-04-22T16:28:11.231389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.907109ms","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-04-22T16:28:11.231206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.392002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T16:28:11.231387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.903477ms","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-04-22T16:28:11.231499Z","caller":"traceutil/trace.go:171","msg":"trace[108182061] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1034; }","duration":"242.72624ms","start":"2026-04-22T16:28:10.988752Z","end":"2026-04-22T16:28:11.231479Z","steps":["trace[108182061] 'agreement among raft nodes before linearized reading' (duration: 242.376322ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.231268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.648688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T16:28:11.231631Z","caller":"traceutil/trace.go:171","msg":"trace[48506093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1034; }","duration":"326.042988ms","start":"2026-04-22T16:28:10.905578Z","end":"2026-04-22T16:28:11.231621Z","steps":["trace[48506093] 'agreement among raft nodes before linearized reading' (duration: 325.559256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.231676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:10.905555Z","time spent":"326.104908ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-22T16:28:11.231367Z","caller":"traceutil/trace.go:171","msg":"trace[1916949329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1034; }","duration":"353.898377ms","start":"2026-04-22T16:28:10.877406Z","end":"2026-04-22T16:28:11.231304Z","steps":["trace[1916949329] 'agreement among raft nodes before linearized reading' (duration: 353.427147ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:11.231515Z","caller":"traceutil/trace.go:171","msg":"trace[105094977] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:1034; }","duration":"208.05209ms","start":"2026-04-22T16:28:11.023449Z","end":"2026-04-22T16:28:11.231501Z","steps":["trace[105094977] 'agreement among raft nodes before linearized reading' (duration: 207.907817ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.231921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:10.877384Z","time spent":"354.42636ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-22T16:28:11.231463Z","caller":"traceutil/trace.go:171","msg":"trace[11763428] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1034; }","duration":"349.997341ms","start":"2026-04-22T16:28:10.881446Z","end":"2026-04-22T16:28:11.231443Z","steps":["trace[11763428] 'agreement among raft nodes before linearized reading' (duration: 349.853168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.232171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:10.881435Z","time spent":"350.724777ms","remote":"127.0.0.1:38822","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-04-22T16:28:11.575736Z","caller":"traceutil/trace.go:171","msg":"trace[2009357441] transaction","detail":"{read_only:false; response_revision:1037; number_of_response:1; }","duration":"338.554192ms","start":"2026-04-22T16:28:11.237157Z","end":"2026-04-22T16:28:11.575711Z","steps":["trace[2009357441] 'process raft request' (duration: 338.47679ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:11.575844Z","caller":"traceutil/trace.go:171","msg":"trace[1779595857] transaction","detail":"{read_only:false; response_revision:1036; number_of_response:1; }","duration":"338.93548ms","start":"2026-04-22T16:28:11.23688Z","end":"2026-04-22T16:28:11.575816Z","steps":["trace[1779595857] 'process raft request' (duration: 338.593152ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.575955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:11.236871Z","time spent":"339.024012ms","remote":"127.0.0.1:38822","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-04-22T16:28:11.576033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:11.23715Z","time spent":"338.658304ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:11.576393Z","caller":"traceutil/trace.go:171","msg":"trace[2056186892] transaction","detail":"{read_only:false; response_revision:1038; number_of_response:1; }","duration":"339.098653ms","start":"2026-04-22T16:28:11.237234Z","end":"2026-04-22T16:28:11.576333Z","steps":["trace[2056186892] 'process raft request' (duration: 338.440768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:11.576717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:11.237215Z","time spent":"339.301558ms","remote":"127.0.0.1:38822","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-04-22T16:28:11.586298Z","caller":"traceutil/trace.go:171","msg":"trace[1016597636] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"219.097722ms","start":"2026-04-22T16:28:11.367185Z","end":"2026-04-22T16:28:11.586283Z","steps":["trace[1016597636] 'process raft request' (duration: 219.029261ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:12.489201Z","caller":"traceutil/trace.go:171","msg":"trace[1474497067] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"134.750043ms","start":"2026-04-22T16:28:12.354421Z","end":"2026-04-22T16:28:12.489171Z","steps":["trace[1474497067] 'process raft request' (duration: 134.60252ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:13.771903Z","caller":"traceutil/trace.go:171","msg":"trace[585198825] linearizableReadLoop","detail":"{readStateIndex:1085; appliedIndex:1084; }","duration":"190.768211ms","start":"2026-04-22T16:28:13.581115Z","end":"2026-04-22T16:28:13.771883Z","steps":["trace[585198825] 'read index received' (duration: 112.432074ms)","trace[585198825] 'applied index is now lower than readState.Index' (duration: 78.335187ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:13.772015Z","caller":"traceutil/trace.go:171","msg":"trace[656925366] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"382.672928ms","start":"2026-04-22T16:28:13.38931Z","end":"2026-04-22T16:28:13.771983Z","steps":["trace[656925366] 'process raft request' (duration: 304.33458ms)","trace[656925366] 'compare' (duration: 77.883578ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:13.772077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.910835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:28:13.772112Z","caller":"traceutil/trace.go:171","msg":"trace[1865325880] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1043; }","duration":"190.955686ms","start":"2026-04-22T16:28:13.581146Z","end":"2026-04-22T16:28:13.772102Z","steps":["trace[1865325880] 'agreement among raft nodes before linearized reading' (duration: 190.845964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:13.772077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.958596ms","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-04-22T16:28:13.772134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.541189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-22T16:28:13.772139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:13.389281Z","time spent":"382.795761ms","remote":"127.0.0.1:38822","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-04-22T16:28:13.772153Z","caller":"traceutil/trace.go:171","msg":"trace[1530393721] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1043; }","duration":"191.081898ms","start":"2026-04-22T16:28:13.581062Z","end":"2026-04-22T16:28:13.772143Z","steps":["trace[1530393721] 'agreement among raft nodes before linearized reading' (duration: 190.923365ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:13.772167Z","caller":"traceutil/trace.go:171","msg":"trace[824114172] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1043; }","duration":"108.6158ms","start":"2026-04-22T16:28:13.663541Z","end":"2026-04-22T16:28:13.772157Z","steps":["trace[824114172] 'agreement among raft nodes before linearized reading' (duration: 108.556819ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:13.77227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.793772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:28:13.772334Z","caller":"traceutil/trace.go:171","msg":"trace[954985949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1043; }","duration":"190.876994ms","start":"2026-04-22T16:28:13.581429Z","end":"2026-04-22T16:28:13.772306Z","steps":["trace[954985949] 'agreement among raft nodes before linearized reading' (duration: 190.72676ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:13.91212Z","caller":"traceutil/trace.go:171","msg":"trace[1494117911] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"134.379146ms","start":"2026-04-22T16:28:13.777717Z","end":"2026-04-22T16:28:13.912096Z","steps":["trace[1494117911] 'process raft request' (duration: 131.860261ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:13.912593Z","caller":"traceutil/trace.go:171","msg":"trace[1835992164] transaction","detail":"{read_only:false; response_revision:1045; number_of_response:1; }","duration":"134.327715ms","start":"2026-04-22T16:28:13.778254Z","end":"2026-04-22T16:28:13.912582Z","steps":["trace[1835992164] 'process raft request' (duration: 133.799443ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:13.913007Z","caller":"traceutil/trace.go:171","msg":"trace[2101552774] transaction","detail":"{read_only:false; response_revision:1046; number_of_response:1; }","duration":"134.258724ms","start":"2026-04-22T16:28:13.778701Z","end":"2026-04-22T16:28:13.91296Z","steps":["trace[2101552774] 'process raft request' (duration: 133.836844ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:14.929899Z","caller":"traceutil/trace.go:171","msg":"trace[1939253999] linearizableReadLoop","detail":"{readStateIndex:1089; appliedIndex:1088; }","duration":"264.716663ms","start":"2026-04-22T16:28:14.665163Z","end":"2026-04-22T16:28:14.92988Z","steps":["trace[1939253999] 'read index received' (duration: 264.520799ms)","trace[1939253999] 'applied index is now lower than readState.Index' (duration: 194.854µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:14.930028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.857656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:14.930106Z","caller":"traceutil/trace.go:171","msg":"trace[144310101] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1047; }","duration":"264.979068ms","start":"2026-04-22T16:28:14.665106Z","end":"2026-04-22T16:28:14.930085Z","steps":["trace[144310101] 'agreement among raft nodes before linearized reading' (duration: 264.853405ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:14.930059Z","caller":"traceutil/trace.go:171","msg":"trace[93951989] transaction","detail":"{read_only:false; response_revision:1047; number_of_response:1; }","duration":"433.288668ms","start":"2026-04-22T16:28:14.496636Z","end":"2026-04-22T16:28:14.929925Z","steps":["trace[93951989] 'process raft request' (duration: 433.102654ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:14.930305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:14.49662Z","time spent":"433.599635ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:15.389279Z","caller":"traceutil/trace.go:171","msg":"trace[1260815616] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"137.352821ms","start":"2026-04-22T16:28:15.251903Z","end":"2026-04-22T16:28:15.389256Z","steps":["trace[1260815616] 'process raft request' (duration: 137.065205ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:25.568146Z","caller":"traceutil/trace.go:171","msg":"trace[697944717] transaction","detail":"{read_only:false; response_revision:1134; number_of_response:1; }","duration":"123.848184ms","start":"2026-04-22T16:28:25.444268Z","end":"2026-04-22T16:28:25.568116Z","steps":["trace[697944717] 'process raft request' (duration: 58.836159ms)","trace[697944717] 'compare' (duration: 64.890363ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:26.282252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.653992ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:26.282465Z","caller":"traceutil/trace.go:171","msg":"trace[1439997367] transaction","detail":"{read_only:false; response_revision:1137; number_of_response:1; }","duration":"302.88301ms","start":"2026-04-22T16:28:25.979561Z","end":"2026-04-22T16:28:26.282444Z","steps":["trace[1439997367] 'process raft request' (duration: 302.799018ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:26.282504Z","caller":"traceutil/trace.go:171","msg":"trace[1358533855] transaction","detail":"{read_only:false; response_revision:1136; number_of_response:1; }","duration":"303.821419ms","start":"2026-04-22T16:28:25.97866Z","end":"2026-04-22T16:28:26.282481Z","steps":["trace[1358533855] 'process raft request' (duration: 126.846ms)","trace[1358533855] 'compare' (duration: 176.508939ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:26.282564Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:25.97955Z","time spent":"302.969241ms","remote":"127.0.0.1:38822","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-04-22T16:28:26.282624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:25.978644Z","time spent":"303.920611ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:29.231904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.601442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-22T16:28:29.231988Z","caller":"traceutil/trace.go:171","msg":"trace[1880026955] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1140; }","duration":"946.714245ms","start":"2026-04-22T16:28:28.285256Z","end":"2026-04-22T16:28:29.231971Z","steps":["trace[1880026955] 'range keys from in-memory index tree' (duration: 946.486459ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:29.232023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:28.285244Z","time spent":"946.770936ms","remote":"127.0.0.1:38822","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-04-22T16:28:29.232115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.799387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:29.232181Z","caller":"traceutil/trace.go:171","msg":"trace[1330551393] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1140; }","duration":"319.902839ms","start":"2026-04-22T16:28:28.912262Z","end":"2026-04-22T16:28:29.232165Z","steps":["trace[1330551393] 'count revisions from in-memory index tree' (duration: 319.721186ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:29.232196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.203895812s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-22T16:28:29.23222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:28.91224Z","time spent":"319.969521ms","remote":"127.0.0.1:60770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:28:29.232244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"946.359897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-22T16:28:29.232261Z","caller":"traceutil/trace.go:171","msg":"trace[1450908620] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1140; }","duration":"1.203998925s","start":"2026-04-22T16:28:28.028242Z","end":"2026-04-22T16:28:29.232241Z","steps":["trace[1450908620] 'range keys from in-memory index tree' (duration: 1.203740269s)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:29.232279Z","caller":"traceutil/trace.go:171","msg":"trace[1175977211] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1140; }","duration":"946.403328ms","start":"2026-04-22T16:28:28.285867Z","end":"2026-04-22T16:28:29.23227Z","steps":["trace[1175977211] 'range keys from in-memory index tree' (duration: 946.268485ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:29.232298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:28.028221Z","time spent":"1.204070106s","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-22T16:28:29.232306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:28.285862Z","time spent":"946.437119ms","remote":"127.0.0.1:38822","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-04-22T16:28:29.232525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"873.867208ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-22T16:28:29.232538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.715425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:29.232558Z","caller":"traceutil/trace.go:171","msg":"trace[2098791975] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:1140; }","duration":"873.94341ms","start":"2026-04-22T16:28:28.358607Z","end":"2026-04-22T16:28:29.23255Z","steps":["trace[2098791975] 'count revisions from in-memory index tree' (duration: 873.772316ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:29.232572Z","caller":"traceutil/trace.go:171","msg":"trace[1074548845] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:1140; }","duration":"255.792766ms","start":"2026-04-22T16:28:28.976769Z","end":"2026-04-22T16:28:29.232562Z","steps":["trace[1074548845] 'count revisions from in-memory index tree' (duration: 255.652633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:29.23259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:28.358585Z","time spent":"873.997841ms","remote":"127.0.0.1:38920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":70,"response size":29,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-04-22T16:28:29.23275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.291043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:28:29.232782Z","caller":"traceutil/trace.go:171","msg":"trace[1428149846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"149.348143ms","start":"2026-04-22T16:28:29.083426Z","end":"2026-04-22T16:28:29.232774Z","steps":["trace[1428149846] 'range keys from in-memory index tree' (duration: 149.184581ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:29.343296Z","caller":"traceutil/trace.go:171","msg":"trace[1245839209] transaction","detail":"{read_only:false; response_revision:1141; number_of_response:1; }","duration":"106.737538ms","start":"2026-04-22T16:28:29.236537Z","end":"2026-04-22T16:28:29.343275Z","steps":["trace[1245839209] 'process raft request' (duration: 106.610346ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:30.025046Z","caller":"traceutil/trace.go:171","msg":"trace[722162735] transaction","detail":"{read_only:false; response_revision:1142; number_of_response:1; }","duration":"788.429027ms","start":"2026-04-22T16:28:29.236581Z","end":"2026-04-22T16:28:30.02501Z","steps":["trace[722162735] 'process raft request' (duration: 787.231471ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:30.025061Z","caller":"traceutil/trace.go:171","msg":"trace[1683442895] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"788.0969ms","start":"2026-04-22T16:28:29.236933Z","end":"2026-04-22T16:28:30.02503Z","steps":["trace[1683442895] 'process raft request' (duration: 787.63039ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:30.025236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.236914Z","time spent":"788.224882ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:30.02522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.236557Z","time spent":"788.57143ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:30.025057Z","caller":"traceutil/trace.go:171","msg":"trace[88019380] transaction","detail":"{read_only:false; response_revision:1143; number_of_response:1; }","duration":"788.280974ms","start":"2026-04-22T16:28:29.236751Z","end":"2026-04-22T16:28:30.025032Z","steps":["trace[88019380] 'process raft request' (duration: 787.63639ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:30.025375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.236743Z","time spent":"788.587441ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:30.026946Z","caller":"traceutil/trace.go:171","msg":"trace[953848271] linearizableReadLoop","detail":"{readStateIndex:1192; appliedIndex:1188; }","duration":"445.484146ms","start":"2026-04-22T16:28:29.581442Z","end":"2026-04-22T16:28:30.026926Z","steps":["trace[953848271] 'read index received' (duration: 442.851158ms)","trace[953848271] 'applied index is now lower than readState.Index' (duration: 2.631868ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:30.027056Z","caller":"traceutil/trace.go:171","msg":"trace[1443512497] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"570.196245ms","start":"2026-04-22T16:28:29.456848Z","end":"2026-04-22T16:28:30.027045Z","steps":["trace[1443512497] 'process raft request' (duration: 569.96194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:30.027109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.456822Z","time spent":"570.254016ms","remote":"127.0.0.1:38822","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-04-22T16:28:30.027123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.747224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:30.027175Z","caller":"traceutil/trace.go:171","msg":"trace[930299758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1145; }","duration":"364.837727ms","start":"2026-04-22T16:28:29.662323Z","end":"2026-04-22T16:28:30.027161Z","steps":["trace[930299758] 'agreement among raft nodes before linearized reading' (duration: 364.767715ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:30.027205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.662303Z","time spent":"364.895687ms","remote":"127.0.0.1:38570","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-04-22T16:28:30.027492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.048498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-22T16:28:30.027576Z","caller":"traceutil/trace.go:171","msg":"trace[536316877] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1145; }","duration":"446.181651ms","start":"2026-04-22T16:28:29.581379Z","end":"2026-04-22T16:28:30.027561Z","steps":["trace[536316877] 'agreement among raft nodes before linearized reading' (duration: 445.888595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:30.027661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:29.58133Z","time spent":"446.319194ms","remote":"127.0.0.1:38822","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-04-22T16:28:44.718687Z","caller":"traceutil/trace.go:171","msg":"trace[1382199664] transaction","detail":"{read_only:false; response_revision:1212; number_of_response:1; }","duration":"427.413445ms","start":"2026-04-22T16:28:44.291232Z","end":"2026-04-22T16:28:44.718646Z","steps":["trace[1382199664] 'process raft request' (duration: 427.17783ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:44.718834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:44.29121Z","time spent":"427.542668ms","remote":"127.0.0.1:38822","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-04-22T16:28:45.04653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.052391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5331"} {"level":"info","ts":"2026-04-22T16:28:45.046607Z","caller":"traceutil/trace.go:171","msg":"trace[1033619504] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1212; }","duration":"260.162553ms","start":"2026-04-22T16:28:44.786422Z","end":"2026-04-22T16:28:45.046584Z","steps":["trace[1033619504] 'range keys from in-memory index tree' (duration: 259.841966ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:45.158515Z","caller":"traceutil/trace.go:171","msg":"trace[1207659803] transaction","detail":"{read_only:false; response_revision:1213; number_of_response:1; }","duration":"103.336903ms","start":"2026-04-22T16:28:45.055151Z","end":"2026-04-22T16:28:45.158488Z","steps":["trace[1207659803] 'process raft request' (duration: 103.22738ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:47.56473Z","caller":"traceutil/trace.go:171","msg":"trace[776170524] transaction","detail":"{read_only:false; response_revision:1228; number_of_response:1; }","duration":"126.1557ms","start":"2026-04-22T16:28:47.438557Z","end":"2026-04-22T16:28:47.564713Z","steps":["trace[776170524] 'process raft request' (duration: 126.030537ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.269366Z","caller":"traceutil/trace.go:171","msg":"trace[907322097] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"121.887305ms","start":"2026-04-22T16:28:48.147455Z","end":"2026-04-22T16:28:48.269342Z","steps":["trace[907322097] 'process raft request' (duration: 121.839774ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.269411Z","caller":"traceutil/trace.go:171","msg":"trace[1904705840] transaction","detail":"{read_only:false; response_revision:1232; number_of_response:1; }","duration":"122.448728ms","start":"2026-04-22T16:28:48.146947Z","end":"2026-04-22T16:28:48.269396Z","steps":["trace[1904705840] 'process raft request' (duration: 122.322015ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:48.269803Z","caller":"traceutil/trace.go:171","msg":"trace[1101679825] transaction","detail":"{read_only:false; response_revision:1231; number_of_response:1; }","duration":"122.491169ms","start":"2026-04-22T16:28:48.146837Z","end":"2026-04-22T16:28:48.269328Z","steps":["trace[1101679825] 'process raft request' (duration: 122.309795ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:50.189165Z","caller":"traceutil/trace.go:171","msg":"trace[433727866] linearizableReadLoop","detail":"{readStateIndex:1292; appliedIndex:1291; }","duration":"141.91319ms","start":"2026-04-22T16:28:50.047229Z","end":"2026-04-22T16:28:50.189142Z","steps":["trace[433727866] 'read index received' (duration: 141.778397ms)","trace[433727866] 'applied index is now lower than readState.Index' (duration: 133.813µs)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:50.189358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.100693ms","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-04-22T16:28:50.189398Z","caller":"traceutil/trace.go:171","msg":"trace[2092661809] range","detail":"{range_begin:/registry/masterleases/199.19.213.180; range_end:; response_count:1; response_revision:1241; }","duration":"142.200996ms","start":"2026-04-22T16:28:50.047185Z","end":"2026-04-22T16:28:50.189386Z","steps":["trace[2092661809] 'agreement among raft nodes before linearized reading' (duration: 142.045142ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:50.189416Z","caller":"traceutil/trace.go:171","msg":"trace[841595923] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"618.581918ms","start":"2026-04-22T16:28:49.570805Z","end":"2026-04-22T16:28:50.189387Z","steps":["trace[841595923] 'process raft request' (duration: 618.197119ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:50.189629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:49.570779Z","time spent":"618.754502ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:50.553478Z","caller":"traceutil/trace.go:171","msg":"trace[1403756642] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"215.193126ms","start":"2026-04-22T16:28:50.338262Z","end":"2026-04-22T16:28:50.553455Z","steps":["trace[1403756642] 'process raft request' (duration: 148.600207ms)","trace[1403756642] 'compare' (duration: 66.380884ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:50.553522Z","caller":"traceutil/trace.go:171","msg":"trace[743376661] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"213.975768ms","start":"2026-04-22T16:28:50.339504Z","end":"2026-04-22T16:28:50.553479Z","steps":["trace[743376661] 'process raft request' (duration: 213.849805ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:50.553582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.267435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-04-22T16:28:50.553458Z","caller":"traceutil/trace.go:171","msg":"trace[715751885] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1296; }","duration":"214.122222ms","start":"2026-04-22T16:28:50.33929Z","end":"2026-04-22T16:28:50.553412Z","steps":["trace[715751885] 'read index received' (duration: 15.460663ms)","trace[715751885] 'applied index is now lower than readState.Index' (duration: 198.660089ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:50.553653Z","caller":"traceutil/trace.go:171","msg":"trace[2100338921] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1247; }","duration":"214.363357ms","start":"2026-04-22T16:28:50.339275Z","end":"2026-04-22T16:28:50.553638Z","steps":["trace[2100338921] 'agreement among raft nodes before linearized reading' (duration: 214.238394ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:51.212704Z","caller":"traceutil/trace.go:171","msg":"trace[118486539] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"116.252579ms","start":"2026-04-22T16:28:51.096361Z","end":"2026-04-22T16:28:51.212614Z","steps":["trace[118486539] 'process raft request' (duration: 116.098536ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:52.086465Z","caller":"traceutil/trace.go:171","msg":"trace[453848023] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"127.260674ms","start":"2026-04-22T16:28:51.959179Z","end":"2026-04-22T16:28:52.086439Z","steps":["trace[453848023] 'process raft request' (duration: 73.017181ms)","trace[453848023] 'compare' (duration: 54.09343ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:52.47472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.618532ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:52.47482Z","caller":"traceutil/trace.go:171","msg":"trace[318198631] linearizableReadLoop","detail":"{readStateIndex:1307; appliedIndex:1306; }","duration":"281.378904ms","start":"2026-04-22T16:28:52.193425Z","end":"2026-04-22T16:28:52.474803Z","steps":["trace[318198631] 'read index received' (duration: 135.524627ms)","trace[318198631] 'applied index is now lower than readState.Index' (duration: 145.853167ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:52.474888Z","caller":"traceutil/trace.go:171","msg":"trace[391620836] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"383.533412ms","start":"2026-04-22T16:28:52.091343Z","end":"2026-04-22T16:28:52.474877Z","steps":["trace[391620836] 'process raft request' (duration: 237.674155ms)","trace[391620836] 'compare' (duration: 145.5259ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:52.474947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.091313Z","time spent":"383.606043ms","remote":"127.0.0.1:38822","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-04-22T16:28:52.475138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.725382ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-22T16:28:52.475168Z","caller":"traceutil/trace.go:171","msg":"trace[1255153917] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1255; }","duration":"281.794023ms","start":"2026-04-22T16:28:52.193366Z","end":"2026-04-22T16:28:52.47516Z","steps":["trace[1255153917] 'agreement among raft nodes before linearized reading' (duration: 281.682201ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:28:52.509057Z","caller":"traceutil/trace.go:171","msg":"trace[457831269] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"279.016841ms","start":"2026-04-22T16:28:52.230004Z","end":"2026-04-22T16:28:52.509021Z","steps":["trace[457831269] 'process raft request' (duration: 278.726925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:52.509409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.260784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-22T16:28:52.509553Z","caller":"traceutil/trace.go:171","msg":"trace[88756264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"150.445527ms","start":"2026-04-22T16:28:52.359084Z","end":"2026-04-22T16:28:52.509529Z","steps":["trace[88756264] 'agreement among raft nodes before linearized reading' (duration: 150.188002ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:52.509712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.073176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-22T16:28:52.509834Z","caller":"traceutil/trace.go:171","msg":"trace[1825205384] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"171.809172ms","start":"2026-04-22T16:28:52.337986Z","end":"2026-04-22T16:28:52.509796Z","steps":["trace[1825205384] 'agreement among raft nodes before linearized reading' (duration: 170.942223ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:53.044278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.922305ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:53.044501Z","caller":"traceutil/trace.go:171","msg":"trace[342730817] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1311; }","duration":"380.880943ms","start":"2026-04-22T16:28:52.663605Z","end":"2026-04-22T16:28:53.044486Z","steps":["trace[342730817] 'read index received' (duration: 196.714236ms)","trace[342730817] 'applied index is now lower than readState.Index' (duration: 184.165807ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:53.044593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.994075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:53.044617Z","caller":"traceutil/trace.go:171","msg":"trace[1074615711] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1261; }","duration":"381.056917ms","start":"2026-04-22T16:28:52.663552Z","end":"2026-04-22T16:28:53.044609Z","steps":["trace[1074615711] 'agreement among raft nodes before linearized reading' (duration: 380.980305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:53.044648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.663532Z","time spent":"381.108558ms","remote":"127.0.0.1:38570","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-04-22T16:28:53.044709Z","caller":"traceutil/trace.go:171","msg":"trace[1470539515] transaction","detail":"{read_only:false; response_revision:1261; number_of_response:1; }","duration":"482.335424ms","start":"2026-04-22T16:28:52.562345Z","end":"2026-04-22T16:28:53.04468Z","steps":["trace[1470539515] 'process raft request' (duration: 482.027877ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:53.044851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.562335Z","time spent":"482.440456ms","remote":"127.0.0.1:38822","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-04-22T16:28:53.044945Z","caller":"traceutil/trace.go:171","msg":"trace[1555553270] transaction","detail":"{read_only:false; response_revision:1260; number_of_response:1; }","duration":"482.868777ms","start":"2026-04-22T16:28:52.562042Z","end":"2026-04-22T16:28:53.044911Z","steps":["trace[1555553270] 'process raft request' (duration: 18.213325ms)","trace[1555553270] 'compare' (duration: 463.68113ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:53.045109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.562022Z","time spent":"482.99925ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:28:53.231127Z","caller":"traceutil/trace.go:171","msg":"trace[1363668795] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"563.584158ms","start":"2026-04-22T16:28:52.66752Z","end":"2026-04-22T16:28:53.231105Z","steps":["trace[1363668795] 'process raft request' (duration: 522.514636ms)","trace[1363668795] 'compare' (duration: 40.844987ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:53.231022Z","caller":"traceutil/trace.go:171","msg":"trace[1098513310] linearizableReadLoop","detail":"{readStateIndex:1314; appliedIndex:1313; }","duration":"185.133528ms","start":"2026-04-22T16:28:53.045857Z","end":"2026-04-22T16:28:53.23099Z","steps":["trace[1098513310] 'read index received' (duration: 144.114848ms)","trace[1098513310] 'applied index is now lower than readState.Index' (duration: 41.01737ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:53.231268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:52.667503Z","time spent":"563.69721ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:53.231311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.418454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:53.231388Z","caller":"traceutil/trace.go:171","msg":"trace[1848307441] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1262; }","duration":"185.557978ms","start":"2026-04-22T16:28:53.045813Z","end":"2026-04-22T16:28:53.231371Z","steps":["trace[1848307441] 'agreement among raft nodes before linearized reading' (duration: 185.396704ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:57.482183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.709459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-22T16:28:57.482278Z","caller":"traceutil/trace.go:171","msg":"trace[920029527] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1296; }","duration":"426.853244ms","start":"2026-04-22T16:28:57.055396Z","end":"2026-04-22T16:28:57.482249Z","steps":["trace[920029527] 'range keys from in-memory index tree' (duration: 426.549996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:57.482326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:57.055375Z","time spent":"426.937495ms","remote":"127.0.0.1:38822","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-04-22T16:28:57.482462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.32154ms","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-04-22T16:28:57.482526Z","caller":"traceutil/trace.go:171","msg":"trace[956681025] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:1296; }","duration":"293.431282ms","start":"2026-04-22T16:28:57.189078Z","end":"2026-04-22T16:28:57.48251Z","steps":["trace[956681025] 'count revisions from in-memory index tree' (duration: 293.159726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:57.482493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.941711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-22T16:28:57.482572Z","caller":"traceutil/trace.go:171","msg":"trace[373315061] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1296; }","duration":"425.068653ms","start":"2026-04-22T16:28:57.05749Z","end":"2026-04-22T16:28:57.482558Z","steps":["trace[373315061] 'range keys from in-memory index tree' (duration: 424.723966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:57.482617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:57.057466Z","time spent":"425.135565ms","remote":"127.0.0.1:38822","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-04-22T16:28:57.722708Z","caller":"traceutil/trace.go:171","msg":"trace[1432962358] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"235.977307ms","start":"2026-04-22T16:28:57.486707Z","end":"2026-04-22T16:28:57.722684Z","steps":["trace[1432962358] 'process raft request' (duration: 235.825743ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:58.344241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.790066ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-22T16:28:58.344488Z","caller":"traceutil/trace.go:171","msg":"trace[2144388424] linearizableReadLoop","detail":"{readStateIndex:1352; appliedIndex:1349; }","duration":"680.632635ms","start":"2026-04-22T16:28:57.663838Z","end":"2026-04-22T16:28:58.344471Z","steps":["trace[2144388424] 'read index received' (duration: 58.686132ms)","trace[2144388424] 'applied index is now lower than readState.Index' (duration: 621.945563ms)"],"step_count":2} {"level":"warn","ts":"2026-04-22T16:28:58.344555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"680.722577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-22T16:28:58.34458Z","caller":"traceutil/trace.go:171","msg":"trace[658877272] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"680.784298ms","start":"2026-04-22T16:28:57.663787Z","end":"2026-04-22T16:28:58.344571Z","steps":["trace[658877272] 'agreement among raft nodes before linearized reading' (duration: 680.738997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:58.344609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:57.663766Z","time spent":"680.83628ms","remote":"127.0.0.1:38570","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-04-22T16:28:58.344655Z","caller":"traceutil/trace.go:171","msg":"trace[229148468] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"857.760566ms","start":"2026-04-22T16:28:57.486853Z","end":"2026-04-22T16:28:58.344614Z","steps":["trace[229148468] 'process raft request' (duration: 401.49665ms)","trace[229148468] 'compare' (duration: 455.362267ms)"],"step_count":2} {"level":"info","ts":"2026-04-22T16:28:58.344812Z","caller":"traceutil/trace.go:171","msg":"trace[19701246] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"856.944757ms","start":"2026-04-22T16:28:57.487849Z","end":"2026-04-22T16:28:58.344794Z","steps":["trace[19701246] 'process raft request' (duration: 856.484787ms)"],"step_count":1} {"level":"warn","ts":"2026-04-22T16:28:58.34485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:57.486817Z","time spent":"857.938781ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-22T16:28:58.344908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-22T16:28:57.487839Z","time spent":"857.02283ms","remote":"127.0.0.1:38822","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-22T16:29:56.86401Z","caller":"traceutil/trace.go:171","msg":"trace[2118993218] transaction","detail":"{read_only:false; response_revision:1569; number_of_response:1; }","duration":"119.371205ms","start":"2026-04-22T16:29:56.744614Z","end":"2026-04-22T16:29:56.863985Z","steps":["trace[2118993218] 'process raft request' (duration: 119.202421ms)"],"step_count":1} {"level":"info","ts":"2026-04-22T16:30:23.725469Z","caller":"traceutil/trace.go:171","msg":"trace[1578763700] transaction","detail":"{read_only:false; response_revision:1693; number_of_response:1; }","duration":"126.04491ms","start":"2026-04-22T16:30:23.599404Z","end":"2026-04-22T16:30:23.725449Z","steps":["trace[1578763700] 'process raft request' (duration: 117.677344ms)"],"step_count":1}