{"level":"warn","ts":"2026-05-26T08:54:25.355661Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T08:54:25.355747Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.4: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.204.45.4:2380","--initial-cluster=instance=https://199.204.45.4:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.4:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.4:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-05-26T08:54:25.35581Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-26T08:54:25.355832Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-26T08:54:25.355851Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T08:54:25.356396Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"]} {"level":"info","ts":"2026-05-26T08:54:25.356475Z","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.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.4:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-05-26T08:54:25.360148Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.442902ms"} {"level":"info","ts":"2026-05-26T08:54:25.372374Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"66f7b57d56ad567d","cluster-id":"ec267dcaac715770"} {"level":"info","ts":"2026-05-26T08:54:25.372426Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=()"} {"level":"info","ts":"2026-05-26T08:54:25.372442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 0"} {"level":"info","ts":"2026-05-26T08:54:25.37245Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 66f7b57d56ad567d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-26T08:54:25.372463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became follower at term 1"} {"level":"info","ts":"2026-05-26T08:54:25.372489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"warn","ts":"2026-05-26T08:54:25.378543Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-26T08:54:25.380951Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-26T08:54:25.38259Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-26T08:54:25.385261Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"66f7b57d56ad567d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-26T08:54:25.385471Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"66f7b57d56ad567d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-26T08:54:25.385635Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:54:25.385732Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:54:25.385743Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-26T08:54:25.389513Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d switched to configuration voters=(7419598461045921405)"} {"level":"info","ts":"2026-05-26T08:54:25.390043Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","added-peer-id":"66f7b57d56ad567d","added-peer-peer-urls":["https://199.204.45.4:2380"]} {"level":"info","ts":"2026-05-26T08:54:25.392276Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-26T08:54:25.39249Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"66f7b57d56ad567d","initial-advertise-peer-urls":["https://199.204.45.4:2380"],"listen-peer-urls":["https://199.204.45.4:2380"],"advertise-client-urls":["https://199.204.45.4:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.4:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-26T08:54:25.392515Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-26T08:54:25.392624Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-26T08:54:25.392637Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.4:2380"} {"level":"info","ts":"2026-05-26T08:54:25.973155Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d is starting a new election at term 1"} {"level":"info","ts":"2026-05-26T08:54:25.973268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became pre-candidate at term 1"} {"level":"info","ts":"2026-05-26T08:54:25.973309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgPreVoteResp from 66f7b57d56ad567d at term 1"} {"level":"info","ts":"2026-05-26T08:54:25.973326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became candidate at term 2"} {"level":"info","ts":"2026-05-26T08:54:25.973331Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d received MsgVoteResp from 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-26T08:54:25.973342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"66f7b57d56ad567d became leader at term 2"} {"level":"info","ts":"2026-05-26T08:54:25.97335Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 66f7b57d56ad567d elected leader 66f7b57d56ad567d at term 2"} {"level":"info","ts":"2026-05-26T08:54:25.994876Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T08:54:25.994875Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"66f7b57d56ad567d","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.4:2379]}","request-path":"/0/members/66f7b57d56ad567d/attributes","cluster-id":"ec267dcaac715770","publish-timeout":"7s"} {"level":"info","ts":"2026-05-26T08:54:25.995093Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:54:25.99506Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-26T08:54:25.995411Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-26T08:54:25.995587Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-26T08:54:25.996123Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ec267dcaac715770","local-member-id":"66f7b57d56ad567d","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:54:25.996247Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:54:25.996274Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-26T08:54:25.996661Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.4:2379"} {"level":"info","ts":"2026-05-26T08:54:25.998992Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-26T08:54:45.23022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"753.932494ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T08:54:45.230316Z","caller":"traceutil/trace.go:171","msg":"trace[1529361362] linearizableReadLoop","detail":"{readStateIndex:462; appliedIndex:461; }","duration":"818.176174ms","start":"2026-05-26T08:54:44.412127Z","end":"2026-05-26T08:54:45.230303Z","steps":["trace[1529361362] 'read index received' (duration: 64.044846ms)","trace[1529361362] 'applied index is now lower than readState.Index' (duration: 754.130248ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:54:45.23038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.250425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:54:45.230399Z","caller":"traceutil/trace.go:171","msg":"trace[625635477] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:449; }","duration":"818.309566ms","start":"2026-05-26T08:54:44.412083Z","end":"2026-05-26T08:54:45.230392Z","steps":["trace[625635477] 'agreement among raft nodes before linearized reading' (duration: 818.259595ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:45.230423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:44.412062Z","time spent":"818.355977ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:54:45.823829Z","caller":"traceutil/trace.go:171","msg":"trace[1079718572] linearizableReadLoop","detail":"{readStateIndex:463; appliedIndex:462; }","duration":"279.849391ms","start":"2026-05-26T08:54:45.543945Z","end":"2026-05-26T08:54:45.823794Z","steps":["trace[1079718572] 'read index received' (duration: 279.644127ms)","trace[1079718572] 'applied index is now lower than readState.Index' (duration: 203.584µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:54:45.823916Z","caller":"traceutil/trace.go:171","msg":"trace[1941743788] transaction","detail":"{read_only:false; response_revision:450; number_of_response:1; }","duration":"534.147166ms","start":"2026-05-26T08:54:45.289731Z","end":"2026-05-26T08:54:45.823879Z","steps":["trace[1941743788] 'process raft request' (duration: 533.839099ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:45.824116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.952453ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-26T08:54:45.82411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.124527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:54:45.824166Z","caller":"traceutil/trace.go:171","msg":"trace[1373254336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:450; }","duration":"280.073536ms","start":"2026-05-26T08:54:45.544077Z","end":"2026-05-26T08:54:45.82415Z","steps":["trace[1373254336] 'agreement among raft nodes before linearized reading' (duration: 279.941043ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:54:45.824217Z","caller":"traceutil/trace.go:171","msg":"trace[697099268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:450; }","duration":"280.292101ms","start":"2026-05-26T08:54:45.543902Z","end":"2026-05-26T08:54:45.824194Z","steps":["trace[697099268] 'agreement among raft nodes before linearized reading' (duration: 280.050196ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:45.824723Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:45.28971Z","time spent":"534.29469ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:54:46.357284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504260049,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:54:46.553771Z","caller":"traceutil/trace.go:171","msg":"trace[1140427149] transaction","detail":"{read_only:false; response_revision:451; number_of_response:1; }","duration":"723.375442ms","start":"2026-05-26T08:54:45.830367Z","end":"2026-05-26T08:54:46.553743Z","steps":["trace[1140427149] 'process raft request' (duration: 681.558022ms)","trace[1140427149] 'compare' (duration: 41.691918ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:54:46.553909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:45.830328Z","time spent":"723.504375ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:54:47.82974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.927912ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:54:47.829846Z","caller":"traceutil/trace.go:171","msg":"trace[1044652749] linearizableReadLoop","detail":"{readStateIndex:465; appliedIndex:463; }","duration":"1.972791494s","start":"2026-05-26T08:54:45.857034Z","end":"2026-05-26T08:54:47.829826Z","steps":["trace[1044652749] 'read index received' (duration: 654.865269ms)","trace[1044652749] 'applied index is now lower than readState.Index' (duration: 1.317924955s)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:54:47.829895Z","caller":"traceutil/trace.go:171","msg":"trace[1699074938] transaction","detail":"{read_only:false; response_revision:452; number_of_response:1; }","duration":"1.999341994s","start":"2026-05-26T08:54:45.83052Z","end":"2026-05-26T08:54:47.829862Z","steps":["trace[1699074938] 'process raft request' (duration: 1.326207778s)","trace[1699074938] 'compare' (duration: 672.629835ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:54:47.829958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.972943686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:54:47.830016Z","caller":"traceutil/trace.go:171","msg":"trace[795683898] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:452; }","duration":"1.973000318s","start":"2026-05-26T08:54:45.857005Z","end":"2026-05-26T08:54:47.830006Z","steps":["trace[795683898] 'agreement among raft nodes before linearized reading' (duration: 1.972910166s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:47.830029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:45.829936Z","time spent":"2.000009517s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:54:47.83006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:45.856919Z","time spent":"1.97311862s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:54:47.830246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.41810134s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:54:47.830277Z","caller":"traceutil/trace.go:171","msg":"trace[517033532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:452; }","duration":"1.41816505s","start":"2026-05-26T08:54:46.412101Z","end":"2026-05-26T08:54:47.830266Z","steps":["trace[517033532] 'agreement among raft nodes before linearized reading' (duration: 1.418119249s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:47.830303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:46.412077Z","time spent":"1.418220872s","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:54:48.156799Z","caller":"traceutil/trace.go:171","msg":"trace[616494371] transaction","detail":"{read_only:false; response_revision:453; number_of_response:1; }","duration":"323.343931ms","start":"2026-05-26T08:54:47.833426Z","end":"2026-05-26T08:54:48.15677Z","steps":["trace[616494371] 'process raft request' (duration: 323.189637ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:48.156982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:47.833413Z","time spent":"323.473243ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:54:48.158052Z","caller":"traceutil/trace.go:171","msg":"trace[1265000657] linearizableReadLoop","detail":"{readStateIndex:467; appliedIndex:465; }","duration":"320.049418ms","start":"2026-05-26T08:54:47.837987Z","end":"2026-05-26T08:54:48.158036Z","steps":["trace[1265000657] 'read index received' (duration: 318.76351ms)","trace[1265000657] 'applied index is now lower than readState.Index' (duration: 1.285298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:54:48.158149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.14439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:54:48.158173Z","caller":"traceutil/trace.go:171","msg":"trace[1805791306] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:454; }","duration":"320.212242ms","start":"2026-05-26T08:54:47.837955Z","end":"2026-05-26T08:54:48.158167Z","steps":["trace[1805791306] 'agreement among raft nodes before linearized reading' (duration: 320.14759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:48.158191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:47.837948Z","time spent":"320.239302ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:54:48.158361Z","caller":"traceutil/trace.go:171","msg":"trace[490662169] transaction","detail":"{read_only:false; response_revision:454; number_of_response:1; }","duration":"324.214998ms","start":"2026-05-26T08:54:47.834107Z","end":"2026-05-26T08:54:48.158322Z","steps":["trace[490662169] 'process raft request' (duration: 323.84587ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:48.158682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:47.834098Z","time spent":"324.362132ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:54:53.264837Z","caller":"traceutil/trace.go:171","msg":"trace[2063388505] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"677.483841ms","start":"2026-05-26T08:54:52.587249Z","end":"2026-05-26T08:54:53.264732Z","steps":["trace[2063388505] 'process raft request' (duration: 677.198235ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:54:53.265018Z","caller":"traceutil/trace.go:171","msg":"trace[602714874] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"630.694ms","start":"2026-05-26T08:54:52.634299Z","end":"2026-05-26T08:54:53.264993Z","steps":["trace[602714874] 'process raft request' (duration: 630.65857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.265192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.634283Z","time spent":"630.810943ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.265196Z","caller":"traceutil/trace.go:171","msg":"trace[2110000447] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"634.539723ms","start":"2026-05-26T08:54:52.630629Z","end":"2026-05-26T08:54:53.265168Z","steps":["trace[2110000447] 'process raft request' (duration: 634.082983ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.265174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.587221Z","time spent":"677.796667ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:54:53.26531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.630607Z","time spent":"634.651206ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.265388Z","caller":"traceutil/trace.go:171","msg":"trace[961586834] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"634.677857ms","start":"2026-05-26T08:54:52.6307Z","end":"2026-05-26T08:54:53.265378Z","steps":["trace[961586834] 'process raft request' (duration: 634.063183ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:54:53.265423Z","caller":"traceutil/trace.go:171","msg":"trace[1941113353] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"677.96084ms","start":"2026-05-26T08:54:52.587457Z","end":"2026-05-26T08:54:53.265418Z","steps":["trace[1941113353] 'process raft request' (duration: 677.177714ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.265438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.630697Z","time spent":"634.720177ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.265468Z","caller":"traceutil/trace.go:171","msg":"trace[1580353275] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"672.823718ms","start":"2026-05-26T08:54:52.59264Z","end":"2026-05-26T08:54:53.265464Z","steps":["trace[1580353275] 'process raft request' (duration: 672.030331ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.265493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.592617Z","time spent":"672.86676ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.265535Z","caller":"traceutil/trace.go:171","msg":"trace[1426551652] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"634.769939ms","start":"2026-05-26T08:54:52.630756Z","end":"2026-05-26T08:54:53.265526Z","steps":["trace[1426551652] 'process raft request' (duration: 634.118155ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:54:53.265582Z","caller":"traceutil/trace.go:171","msg":"trace[1963140552] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"634.782249ms","start":"2026-05-26T08:54:52.630795Z","end":"2026-05-26T08:54:53.265577Z","steps":["trace[1963140552] 'process raft request' (duration: 634.119735ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.26545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.58745Z","time spent":"677.985272ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:54:53.26561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.630792Z","time spent":"634.807739ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:54:53.265576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.630753Z","time spent":"634.804229ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.270236Z","caller":"traceutil/trace.go:171","msg":"trace[1545066759] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"498.647001ms","start":"2026-05-26T08:54:52.771568Z","end":"2026-05-26T08:54:53.270215Z","steps":["trace[1545066759] 'process raft request' (duration: 498.60103ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.270373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.771533Z","time spent":"498.799515ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.270647Z","caller":"traceutil/trace.go:171","msg":"trace[1455316532] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"614.691331ms","start":"2026-05-26T08:54:52.655946Z","end":"2026-05-26T08:54:53.270637Z","steps":["trace[1455316532] 'process raft request' (duration: 613.921065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.270843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.655919Z","time spent":"614.744642ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.270991Z","caller":"traceutil/trace.go:171","msg":"trace[455746625] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"605.116243ms","start":"2026-05-26T08:54:52.665864Z","end":"2026-05-26T08:54:53.27098Z","steps":["trace[455746625] 'process raft request' (duration: 604.094581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.271023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.665842Z","time spent":"605.164833ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:54:53.271095Z","caller":"traceutil/trace.go:171","msg":"trace[952378399] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"590.438612ms","start":"2026-05-26T08:54:52.680651Z","end":"2026-05-26T08:54:53.27109Z","steps":["trace[952378399] 'process raft request' (duration: 589.436181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:54:53.271121Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:52.680637Z","time spent":"590.471253ms","remote":"127.0.0.1:40684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:55:00.421597Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504260338,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:55:00.564154Z","caller":"traceutil/trace.go:171","msg":"trace[1188740] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"642.772625ms","start":"2026-05-26T08:54:59.921358Z","end":"2026-05-26T08:55:00.56413Z","steps":["trace[1188740] 'read index received' (duration: 577.593883ms)","trace[1188740] 'applied index is now lower than readState.Index' (duration: 65.177782ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:55:00.564433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.059722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-26T08:55:00.564466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.926095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:55:00.564518Z","caller":"traceutil/trace.go:171","msg":"trace[1316859294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:610; }","duration":"152.997876ms","start":"2026-05-26T08:55:00.411503Z","end":"2026-05-26T08:55:00.564501Z","steps":["trace[1316859294] 'agreement among raft nodes before linearized reading' (duration: 152.923144ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:00.564526Z","caller":"traceutil/trace.go:171","msg":"trace[120371547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"643.187004ms","start":"2026-05-26T08:54:59.921307Z","end":"2026-05-26T08:55:00.564494Z","steps":["trace[120371547] 'agreement among raft nodes before linearized reading' (duration: 642.898288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:00.564623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:59.921276Z","time spent":"643.336467ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:00.564665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.406739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T08:55:00.564764Z","caller":"traceutil/trace.go:171","msg":"trace[1332052441] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:610; }","duration":"233.559732ms","start":"2026-05-26T08:55:00.331175Z","end":"2026-05-26T08:55:00.564735Z","steps":["trace[1332052441] 'agreement among raft nodes before linearized reading' (duration: 233.300787ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:00.564169Z","caller":"traceutil/trace.go:171","msg":"trace[101770916] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"747.715314ms","start":"2026-05-26T08:54:59.816436Z","end":"2026-05-26T08:55:00.564151Z","steps":["trace[101770916] 'process raft request' (duration: 682.792048ms)","trace[101770916] 'compare' (duration: 64.566598ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:55:00.564891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.518468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T08:55:00.56508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:54:59.816411Z","time spent":"748.553931ms","remote":"127.0.0.1:40736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:00.565045Z","caller":"traceutil/trace.go:171","msg":"trace[1675905236] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:610; }","duration":"225.693471ms","start":"2026-05-26T08:55:00.339332Z","end":"2026-05-26T08:55:00.565025Z","steps":["trace[1675905236] 'agreement among raft nodes before linearized reading' (duration: 225.074878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:00.565219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.273585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-72bgn\" ","response":"range_response_count:1 size:16957"} {"level":"info","ts":"2026-05-26T08:55:00.565266Z","caller":"traceutil/trace.go:171","msg":"trace[763031297] range","detail":"{range_begin:/registry/pods/kube-system/cilium-72bgn; range_end:; response_count:1; response_revision:610; }","duration":"421.365887ms","start":"2026-05-26T08:55:00.143888Z","end":"2026-05-26T08:55:00.565254Z","steps":["trace[763031297] 'agreement among raft nodes before linearized reading' (duration: 420.494648ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:00.565305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.143863Z","time spent":"421.433448ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16980,"request content":"key:\"/registry/pods/kube-system/cilium-72bgn\" "} {"level":"warn","ts":"2026-05-26T08:55:01.01141Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.115692ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:55:01.011573Z","caller":"traceutil/trace.go:171","msg":"trace[1093329933] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"436.6587ms","start":"2026-05-26T08:55:00.574897Z","end":"2026-05-26T08:55:01.011556Z","steps":["trace[1093329933] 'process raft request' (duration: 203.325783ms)","trace[1093329933] 'compare' (duration: 232.97735ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:55:01.011648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.574887Z","time spent":"436.736692ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:01.011833Z","caller":"traceutil/trace.go:171","msg":"trace[2099770784] transaction","detail":"{read_only:false; response_revision:612; number_of_response:1; }","duration":"436.862946ms","start":"2026-05-26T08:55:00.574958Z","end":"2026-05-26T08:55:01.011821Z","steps":["trace[2099770784] 'process raft request' (duration: 436.548998ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:01.011963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.574829Z","time spent":"437.09253ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:01.012484Z","caller":"traceutil/trace.go:171","msg":"trace[1609219002] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"437.182703ms","start":"2026-05-26T08:55:00.575276Z","end":"2026-05-26T08:55:01.012458Z","steps":["trace[1609219002] 'process raft request' (duration: 436.500888ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:01.012552Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.575238Z","time spent":"437.282035ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:01.013474Z","caller":"traceutil/trace.go:171","msg":"trace[238776694] linearizableReadLoop","detail":"{readStateIndex:637; appliedIndex:632; }","duration":"283.524642ms","start":"2026-05-26T08:55:00.729927Z","end":"2026-05-26T08:55:01.013452Z","steps":["trace[238776694] 'read index received' (duration: 48.348824ms)","trace[238776694] 'applied index is now lower than readState.Index' (duration: 235.174828ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:55:01.013549Z","caller":"traceutil/trace.go:171","msg":"trace[942811546] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"426.948019ms","start":"2026-05-26T08:55:00.586557Z","end":"2026-05-26T08:55:01.013505Z","steps":["trace[942811546] 'process raft request' (duration: 426.707934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:01.014118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.586529Z","time spent":"427.510071ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17126,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:01.013883Z","caller":"traceutil/trace.go:171","msg":"trace[1186370860] transaction","detail":"{read_only:false; response_revision:615; number_of_response:1; }","duration":"395.760359ms","start":"2026-05-26T08:55:00.618103Z","end":"2026-05-26T08:55:01.013863Z","steps":["trace[1186370860] 'process raft request' (duration: 395.311249ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:01.01438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.454651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:55:01.014491Z","caller":"traceutil/trace.go:171","msg":"trace[859537518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:615; }","duration":"284.615945ms","start":"2026-05-26T08:55:00.729861Z","end":"2026-05-26T08:55:01.014477Z","steps":["trace[859537518] 'agreement among raft nodes before linearized reading' (duration: 283.684785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:01.015181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:00.618076Z","time spent":"396.290101ms","remote":"127.0.0.1:40886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-26T08:55:01.520923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504260349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:55:02.021815Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504260349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:55:02.311481Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.29109728s","expected-duration":"1s"} {"level":"warn","ts":"2026-05-26T08:55:03.035956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.157068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:55:03.036065Z","caller":"traceutil/trace.go:171","msg":"trace[1615824551] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"2.015809701s","start":"2026-05-26T08:55:01.020236Z","end":"2026-05-26T08:55:03.036045Z","steps":["trace[1615824551] 'process raft request' (duration: 1.291482399s)","trace[1615824551] 'compare' (duration: 723.871502ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:55:03.036138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:01.020211Z","time spent":"2.015884592s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:55:03.229132Z","caller":"traceutil/trace.go:171","msg":"trace[784174056] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:637; }","duration":"2.20845076s","start":"2026-05-26T08:55:01.020661Z","end":"2026-05-26T08:55:03.229112Z","steps":["trace[784174056] 'read index received' (duration: 1.291031529s)","trace[784174056] 'applied index is now lower than readState.Index' (duration: 917.418331ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:55:03.229151Z","caller":"traceutil/trace.go:171","msg":"trace[1636889377] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"1.468295163s","start":"2026-05-26T08:55:01.760834Z","end":"2026-05-26T08:55:03.229129Z","steps":["trace[1636889377] 'process raft request' (duration: 1.468107449s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:03.229301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:01.760803Z","time spent":"1.468418356s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:55:03.229469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.816351191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:55:03.229482Z","caller":"traceutil/trace.go:171","msg":"trace[9377033] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"1.162564887s","start":"2026-05-26T08:55:02.066889Z","end":"2026-05-26T08:55:03.229454Z","steps":["trace[9377033] 'process raft request' (duration: 1.162179598s)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:03.229532Z","caller":"traceutil/trace.go:171","msg":"trace[1324578483] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:618; }","duration":"1.816409912s","start":"2026-05-26T08:55:01.413078Z","end":"2026-05-26T08:55:03.229488Z","steps":["trace[1324578483] 'agreement among raft nodes before linearized reading' (duration: 1.816358611s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:03.229554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:01.413058Z","time spent":"1.816489384s","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:55:03.229544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.20884858s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"warn","ts":"2026-05-26T08:55:03.229607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:02.066863Z","time spent":"1.162673978s","remote":"127.0.0.1:40828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:55:03.229607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.454773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T08:55:03.22962Z","caller":"traceutil/trace.go:171","msg":"trace[311911783] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:618; }","duration":"2.208963022s","start":"2026-05-26T08:55:01.020637Z","end":"2026-05-26T08:55:03.2296Z","steps":["trace[311911783] 'agreement among raft nodes before linearized reading' (duration: 2.208761877s)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:03.22965Z","caller":"traceutil/trace.go:171","msg":"trace[1380716392] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"213.529325ms","start":"2026-05-26T08:55:03.016109Z","end":"2026-05-26T08:55:03.229639Z","steps":["trace[1380716392] 'agreement among raft nodes before linearized reading' (duration: 213.374701ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:03.22967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:01.020628Z","time spent":"2.209033663s","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":216,"request content":"key:\"/registry/serviceaccounts/kube-system/job-controller\" "} {"level":"warn","ts":"2026-05-26T08:55:03.229707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.312083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T08:55:03.229727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.619397ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:55:03.229765Z","caller":"traceutil/trace.go:171","msg":"trace[429825834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:618; }","duration":"209.379754ms","start":"2026-05-26T08:55:03.02037Z","end":"2026-05-26T08:55:03.22975Z","steps":["trace[429825834] 'agreement among raft nodes before linearized reading' (duration: 209.234461ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:03.229805Z","caller":"traceutil/trace.go:171","msg":"trace[898148769] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:618; }","duration":"213.74973ms","start":"2026-05-26T08:55:03.016037Z","end":"2026-05-26T08:55:03.229786Z","steps":["trace[898148769] 'agreement among raft nodes before linearized reading' (duration: 213.496084ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:05.692183Z","caller":"traceutil/trace.go:171","msg":"trace[2082057513] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"202.855782ms","start":"2026-05-26T08:55:05.489295Z","end":"2026-05-26T08:55:05.692151Z","steps":["trace[2082057513] 'process raft request' (duration: 202.666378ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:19.693013Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504260714,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:55:20.190182Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.052729654s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T08:55:20.190488Z","caller":"traceutil/trace.go:171","msg":"trace[668288241] linearizableReadLoop","detail":"{readStateIndex:804; appliedIndex:803; }","duration":"998.646075ms","start":"2026-05-26T08:55:19.191822Z","end":"2026-05-26T08:55:20.190468Z","steps":["trace[668288241] 'read index received' (duration: 998.505362ms)","trace[668288241] 'applied index is now lower than readState.Index' (duration: 139.713µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:55:20.190568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.137226Z","time spent":"1.053332277s","remote":"127.0.0.1:40666","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-26T08:55:20.190677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.849819ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:55:20.190705Z","caller":"traceutil/trace.go:171","msg":"trace[1740134713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:777; }","duration":"998.953522ms","start":"2026-05-26T08:55:19.191742Z","end":"2026-05-26T08:55:20.190696Z","steps":["trace[1740134713] 'agreement among raft nodes before linearized reading' (duration: 998.792178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.190731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.191715Z","time spent":"999.009772ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:20.190879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"804.612703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:55:20.190942Z","caller":"traceutil/trace.go:171","msg":"trace[1720095426] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:777; }","duration":"804.700805ms","start":"2026-05-26T08:55:19.386225Z","end":"2026-05-26T08:55:20.190926Z","steps":["trace[1720095426] 'agreement among raft nodes before linearized reading' (duration: 804.389588ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.190927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.236126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-txsn9\" ","response":"range_response_count:1 size:3963"} {"level":"warn","ts":"2026-05-26T08:55:20.191021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.386212Z","time spent":"804.798167ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:55:20.191126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.196561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:55:20.191159Z","caller":"traceutil/trace.go:171","msg":"trace[1938921840] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:777; }","duration":"852.268913ms","start":"2026-05-26T08:55:19.338883Z","end":"2026-05-26T08:55:20.191152Z","steps":["trace[1938921840] 'agreement among raft nodes before linearized reading' (duration: 852.196611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.191178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.338863Z","time spent":"852.310133ms","remote":"127.0.0.1:40920","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-05-26T08:55:20.191086Z","caller":"traceutil/trace.go:171","msg":"trace[522831137] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-txsn9; range_end:; response_count:1; response_revision:777; }","duration":"937.47617ms","start":"2026-05-26T08:55:19.253578Z","end":"2026-05-26T08:55:20.191054Z","steps":["trace[522831137] 'agreement among raft nodes before linearized reading' (duration: 937.045281ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.191251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"855.620275ms","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-05-26T08:55:20.191282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.253555Z","time spent":"937.719276ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3986,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-txsn9\" "} {"level":"info","ts":"2026-05-26T08:55:20.191292Z","caller":"traceutil/trace.go:171","msg":"trace[153044064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:777; }","duration":"855.706717ms","start":"2026-05-26T08:55:19.335572Z","end":"2026-05-26T08:55:20.191279Z","steps":["trace[153044064] 'agreement among raft nodes before linearized reading' (duration: 855.575244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.191324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"329.975955ms","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-05-26T08:55:20.19134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.335546Z","time spent":"855.783088ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T08:55:20.191361Z","caller":"traceutil/trace.go:171","msg":"trace[153886905] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:777; }","duration":"330.058887ms","start":"2026-05-26T08:55:19.861291Z","end":"2026-05-26T08:55:20.19135Z","steps":["trace[153886905] 'agreement among raft nodes before linearized reading' (duration: 329.958065ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.191399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.861267Z","time spent":"330.124728ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:20.191482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.482785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:55:20.191539Z","caller":"traceutil/trace.go:171","msg":"trace[1484178889] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:777; }","duration":"779.565967ms","start":"2026-05-26T08:55:19.411954Z","end":"2026-05-26T08:55:20.19152Z","steps":["trace[1484178889] 'agreement among raft nodes before linearized reading' (duration: 779.466335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.191606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:19.411936Z","time spent":"779.661369ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:55:20.350382Z","caller":"traceutil/trace.go:171","msg":"trace[1630765924] transaction","detail":"{read_only:false; response_revision:780; number_of_response:1; }","duration":"153.360154ms","start":"2026-05-26T08:55:20.196996Z","end":"2026-05-26T08:55:20.350356Z","steps":["trace[1630765924] 'process raft request' (duration: 153.282142ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:20.350412Z","caller":"traceutil/trace.go:171","msg":"trace[944140763] transaction","detail":"{read_only:false; response_revision:779; number_of_response:1; }","duration":"153.471046ms","start":"2026-05-26T08:55:20.196896Z","end":"2026-05-26T08:55:20.350367Z","steps":["trace[944140763] 'process raft request' (duration: 153.228001ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:20.350595Z","caller":"traceutil/trace.go:171","msg":"trace[812223964] transaction","detail":"{read_only:false; response_revision:781; number_of_response:1; }","duration":"153.380024ms","start":"2026-05-26T08:55:20.197198Z","end":"2026-05-26T08:55:20.350578Z","steps":["trace[812223964] 'process raft request' (duration: 153.119529ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:20.35127Z","caller":"traceutil/trace.go:171","msg":"trace[597663892] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"153.756472ms","start":"2026-05-26T08:55:20.197499Z","end":"2026-05-26T08:55:20.351255Z","steps":["trace[597663892] 'process raft request' (duration: 153.019096ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:20.351404Z","caller":"traceutil/trace.go:171","msg":"trace[175169131] linearizableReadLoop","detail":"{readStateIndex:811; appliedIndex:805; }","duration":"132.905978ms","start":"2026-05-26T08:55:20.21847Z","end":"2026-05-26T08:55:20.351376Z","steps":["trace[175169131] 'read index received' (duration: 131.59717ms)","trace[175169131] 'applied index is now lower than readState.Index' (duration: 1.307608ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:55:20.351472Z","caller":"traceutil/trace.go:171","msg":"trace[1580161486] transaction","detail":"{read_only:false; response_revision:784; number_of_response:1; }","duration":"152.212548ms","start":"2026-05-26T08:55:20.199248Z","end":"2026-05-26T08:55:20.35146Z","steps":["trace[1580161486] 'process raft request' (duration: 152.034355ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:20.351557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.066371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:420"} {"level":"info","ts":"2026-05-26T08:55:20.351601Z","caller":"traceutil/trace.go:171","msg":"trace[1643765837] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:784; }","duration":"133.157513ms","start":"2026-05-26T08:55:20.218431Z","end":"2026-05-26T08:55:20.351589Z","steps":["trace[1643765837] 'agreement among raft nodes before linearized reading' (duration: 133.055191ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:55:20.351738Z","caller":"traceutil/trace.go:171","msg":"trace[1902984458] transaction","detail":"{read_only:false; response_revision:783; number_of_response:1; }","duration":"153.760542ms","start":"2026-05-26T08:55:20.197963Z","end":"2026-05-26T08:55:20.351723Z","steps":["trace[1902984458] 'process raft request' (duration: 153.19934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.09504Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261189,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:55:59.19492Z","caller":"traceutil/trace.go:171","msg":"trace[1219982529] linearizableReadLoop","detail":"{readStateIndex:1039; appliedIndex:1038; }","duration":"600.322445ms","start":"2026-05-26T08:55:58.594571Z","end":"2026-05-26T08:55:59.194893Z","steps":["trace[1219982529] 'read index received' (duration: 599.965087ms)","trace[1219982529] 'applied index is now lower than readState.Index' (duration: 356.208µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:55:59.195009Z","caller":"traceutil/trace.go:171","msg":"trace[1540971823] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"601.744297ms","start":"2026-05-26T08:55:58.593246Z","end":"2026-05-26T08:55:59.19499Z","steps":["trace[1540971823] 'process raft request' (duration: 601.41292ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.195164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:58.593232Z","time spent":"601.825018ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:55:59.195224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.914607ms","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-05-26T08:55:59.195285Z","caller":"traceutil/trace.go:171","msg":"trace[22793867] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1002; }","duration":"586.003118ms","start":"2026-05-26T08:55:58.609267Z","end":"2026-05-26T08:55:59.195271Z","steps":["trace[22793867] 'agreement among raft nodes before linearized reading' (duration: 585.802644ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.195337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:58.609256Z","time spent":"586.0672ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:59.195386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"600.771274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:55:59.195511Z","caller":"traceutil/trace.go:171","msg":"trace[1091476856] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1002; }","duration":"600.944508ms","start":"2026-05-26T08:55:58.594539Z","end":"2026-05-26T08:55:59.195484Z","steps":["trace[1091476856] 'agreement among raft nodes before linearized reading' (duration: 600.511098ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.1956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:58.594513Z","time spent":"601.077501ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:55:59.195617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.548277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:55:59.195657Z","caller":"traceutil/trace.go:171","msg":"trace[972204279] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"580.619899ms","start":"2026-05-26T08:55:58.615025Z","end":"2026-05-26T08:55:59.195645Z","steps":["trace[972204279] 'agreement among raft nodes before linearized reading' (duration: 580.457236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.195695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:58.614998Z","time spent":"580.689161ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:59.195748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.733462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:55:59.195837Z","caller":"traceutil/trace.go:171","msg":"trace[339769457] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1002; }","duration":"580.863815ms","start":"2026-05-26T08:55:58.614954Z","end":"2026-05-26T08:55:59.195818Z","steps":["trace[339769457] 'agreement among raft nodes before linearized reading' (duration: 580.560028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:55:59.195891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:58.614944Z","time spent":"580.938176ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T08:55:59.913379Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:56:00.020803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.197221Z","time spent":"823.576989ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-26T08:56:00.022007Z","caller":"traceutil/trace.go:171","msg":"trace[1395188619] transaction","detail":"{read_only:false; response_revision:1003; number_of_response:1; }","duration":"821.706596ms","start":"2026-05-26T08:55:59.200228Z","end":"2026-05-26T08:56:00.021935Z","steps":["trace[1395188619] 'process raft request' (duration: 821.031222ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.022211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.200201Z","time spent":"821.885911ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:00.022408Z","caller":"traceutil/trace.go:171","msg":"trace[1198018532] transaction","detail":"{read_only:false; response_revision:1006; number_of_response:1; }","duration":"821.028652ms","start":"2026-05-26T08:55:59.201353Z","end":"2026-05-26T08:56:00.022382Z","steps":["trace[1198018532] 'process raft request' (duration: 820.211944ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.022533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.201346Z","time spent":"821.107814ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:00.022729Z","caller":"traceutil/trace.go:171","msg":"trace[1597782413] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"821.430761ms","start":"2026-05-26T08:55:59.201288Z","end":"2026-05-26T08:56:00.022719Z","steps":["trace[1597782413] 'process raft request' (duration: 820.233294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.023877Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.201276Z","time spent":"822.564336ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:00.022689Z","caller":"traceutil/trace.go:171","msg":"trace[1144586063] linearizableReadLoop","detail":"{readStateIndex:1046; appliedIndex:1041; }","duration":"610.295795ms","start":"2026-05-26T08:55:59.412375Z","end":"2026-05-26T08:56:00.022671Z","steps":["trace[1144586063] 'read index received' (duration: 609.098408ms)","trace[1144586063] 'applied index is now lower than readState.Index' (duration: 1.196447ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:56:00.023098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"610.714484ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:56:00.024038Z","caller":"traceutil/trace.go:171","msg":"trace[1862267515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1008; }","duration":"611.643444ms","start":"2026-05-26T08:55:59.412363Z","end":"2026-05-26T08:56:00.024007Z","steps":["trace[1862267515] 'agreement among raft nodes before linearized reading' (duration: 610.424838ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.024123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.412288Z","time spent":"611.815468ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:56:00.023185Z","caller":"traceutil/trace.go:171","msg":"trace[2103618193] transaction","detail":"{read_only:false; response_revision:1008; number_of_response:1; }","duration":"341.295781ms","start":"2026-05-26T08:55:59.681841Z","end":"2026-05-26T08:56:00.023137Z","steps":["trace[2103618193] 'process raft request' (duration: 340.044203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.024282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.681818Z","time spent":"342.398076ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:00.023716Z","caller":"traceutil/trace.go:171","msg":"trace[1385454384] transaction","detail":"{read_only:false; response_revision:1004; number_of_response:1; }","duration":"823.083007ms","start":"2026-05-26T08:55:59.20058Z","end":"2026-05-26T08:56:00.023663Z","steps":["trace[1385454384] 'process raft request' (duration: 820.860118ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.024444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.200568Z","time spent":"823.829424ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:00.024539Z","caller":"traceutil/trace.go:171","msg":"trace[1229235486] transaction","detail":"{read_only:false; response_revision:1007; number_of_response:1; }","duration":"492.886811ms","start":"2026-05-26T08:55:59.531633Z","end":"2026-05-26T08:56:00.02452Z","steps":["trace[1229235486] 'process raft request' (duration: 490.019368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:00.024674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:55:59.531597Z","time spent":"493.012683ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:21.795625Z","caller":"traceutil/trace.go:171","msg":"trace[1080504562] transaction","detail":"{read_only:false; response_revision:1076; number_of_response:1; }","duration":"500.167333ms","start":"2026-05-26T08:56:21.295433Z","end":"2026-05-26T08:56:21.7956Z","steps":["trace[1080504562] 'process raft request' (duration: 500.02354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:21.79576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:21.295414Z","time spent":"500.276395ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:56:22.315357Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.877438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:56:22.315529Z","caller":"traceutil/trace.go:171","msg":"trace[2144035900] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1076; }","duration":"449.128834ms","start":"2026-05-26T08:56:21.86638Z","end":"2026-05-26T08:56:22.315509Z","steps":["trace[2144035900] 'count revisions from in-memory index tree' (duration: 448.778336ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:22.316259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:21.866361Z","time spent":"449.84921ms","remote":"127.0.0.1:36470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true "} {"level":"info","ts":"2026-05-26T08:56:22.319736Z","caller":"traceutil/trace.go:171","msg":"trace[538109441] transaction","detail":"{read_only:false; response_revision:1077; number_of_response:1; }","duration":"217.046817ms","start":"2026-05-26T08:56:22.102632Z","end":"2026-05-26T08:56:22.319679Z","steps":["trace[538109441] 'process raft request' (duration: 216.787431ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:22.319801Z","caller":"traceutil/trace.go:171","msg":"trace[1982798754] linearizableReadLoop","detail":"{readStateIndex:1119; appliedIndex:1118; }","duration":"191.38212ms","start":"2026-05-26T08:56:22.128186Z","end":"2026-05-26T08:56:22.319568Z","steps":["trace[1982798754] 'read index received' (duration: 191.139355ms)","trace[1982798754] 'applied index is now lower than readState.Index' (duration: 241.195µs)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:56:22.319907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.746008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-26T08:56:22.320002Z","caller":"traceutil/trace.go:171","msg":"trace[10715743] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1077; }","duration":"191.890831ms","start":"2026-05-26T08:56:22.128093Z","end":"2026-05-26T08:56:22.319984Z","steps":["trace[10715743] 'agreement among raft nodes before linearized reading' (duration: 191.649896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:22.320009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.662715ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-26T08:56:22.32013Z","caller":"traceutil/trace.go:171","msg":"trace[1328916512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1077; }","duration":"191.816598ms","start":"2026-05-26T08:56:22.128303Z","end":"2026-05-26T08:56:22.32012Z","steps":["trace[1328916512] 'agreement among raft nodes before linearized reading' (duration: 191.598423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:22.320304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.269305ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-26T08:56:22.320328Z","caller":"traceutil/trace.go:171","msg":"trace[1728445102] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1077; }","duration":"176.428029ms","start":"2026-05-26T08:56:22.143894Z","end":"2026-05-26T08:56:22.320322Z","steps":["trace[1728445102] 'agreement among raft nodes before linearized reading' (duration: 176.146392ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:22.320336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.527096ms","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-05-26T08:56:22.320471Z","caller":"traceutil/trace.go:171","msg":"trace[649698510] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1077; }","duration":"178.701179ms","start":"2026-05-26T08:56:22.141742Z","end":"2026-05-26T08:56:22.320443Z","steps":["trace[649698510] 'agreement among raft nodes before linearized reading' (duration: 178.348782ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:23.949882Z","caller":"traceutil/trace.go:171","msg":"trace[1329604981] transaction","detail":"{read_only:false; response_revision:1082; number_of_response:1; }","duration":"145.521485ms","start":"2026-05-26T08:56:23.804338Z","end":"2026-05-26T08:56:23.94986Z","steps":["trace[1329604981] 'process raft request' (duration: 145.381112ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:25.384345Z","caller":"traceutil/trace.go:171","msg":"trace[435481684] transaction","detail":"{read_only:false; response_revision:1088; number_of_response:1; }","duration":"291.45392ms","start":"2026-05-26T08:56:25.092857Z","end":"2026-05-26T08:56:25.384311Z","steps":["trace[435481684] 'process raft request' (duration: 291.164824ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:26.08491Z","caller":"traceutil/trace.go:171","msg":"trace[1325526035] transaction","detail":"{read_only:false; response_revision:1089; number_of_response:1; }","duration":"126.329021ms","start":"2026-05-26T08:56:25.958548Z","end":"2026-05-26T08:56:26.084877Z","steps":["trace[1325526035] 'process raft request' (duration: 126.096756ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:26.530214Z","caller":"traceutil/trace.go:171","msg":"trace[1435202954] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1133; }","duration":"105.266357ms","start":"2026-05-26T08:56:26.424927Z","end":"2026-05-26T08:56:26.530194Z","steps":["trace[1435202954] 'read index received' (duration: 60.217982ms)","trace[1435202954] 'applied index is now lower than readState.Index' (duration: 45.047505ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:56:26.530485Z","caller":"traceutil/trace.go:171","msg":"trace[2021742173] transaction","detail":"{read_only:false; response_revision:1091; number_of_response:1; }","duration":"105.639445ms","start":"2026-05-26T08:56:26.424824Z","end":"2026-05-26T08:56:26.530463Z","steps":["trace[2021742173] 'process raft request' (duration: 60.358645ms)","trace[2021742173] 'compare' (duration: 44.743298ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:56:26.530604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.655605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:56:26.531105Z","caller":"traceutil/trace.go:171","msg":"trace[1936389630] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1091; }","duration":"106.186596ms","start":"2026-05-26T08:56:26.424898Z","end":"2026-05-26T08:56:26.531085Z","steps":["trace[1936389630] 'agreement among raft nodes before linearized reading' (duration: 105.584873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:26.531259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.914156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:56:26.531369Z","caller":"traceutil/trace.go:171","msg":"trace[1641588583] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1091; }","duration":"104.064579ms","start":"2026-05-26T08:56:26.427286Z","end":"2026-05-26T08:56:26.53135Z","steps":["trace[1641588583] 'agreement among raft nodes before linearized reading' (duration: 103.763343ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:26.531558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.683554ms","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-05-26T08:56:26.531603Z","caller":"traceutil/trace.go:171","msg":"trace[410906809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1091; }","duration":"105.747356ms","start":"2026-05-26T08:56:26.425845Z","end":"2026-05-26T08:56:26.531592Z","steps":["trace[410906809] 'agreement among raft nodes before linearized reading' (duration: 105.078332ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:40.763098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.504793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:56:40.763293Z","caller":"traceutil/trace.go:171","msg":"trace[2117085324] linearizableReadLoop","detail":"{readStateIndex:1240; appliedIndex:1238; }","duration":"117.246251ms","start":"2026-05-26T08:56:40.64603Z","end":"2026-05-26T08:56:40.763277Z","steps":["trace[2117085324] 'read index received' (duration: 15.574204ms)","trace[2117085324] 'applied index is now lower than readState.Index' (duration: 101.670737ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:56:40.763446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.408244ms","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-05-26T08:56:40.763434Z","caller":"traceutil/trace.go:171","msg":"trace[1403898140] transaction","detail":"{read_only:false; response_revision:1192; number_of_response:1; }","duration":"117.864774ms","start":"2026-05-26T08:56:40.645527Z","end":"2026-05-26T08:56:40.763392Z","steps":["trace[1403898140] 'process raft request' (duration: 117.692361ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:40.763453Z","caller":"traceutil/trace.go:171","msg":"trace[18773829] transaction","detail":"{read_only:false; response_revision:1191; number_of_response:1; }","duration":"118.234543ms","start":"2026-05-26T08:56:40.645193Z","end":"2026-05-26T08:56:40.763427Z","steps":["trace[18773829] 'process raft request' (duration: 16.320301ms)","trace[18773829] 'compare' (duration: 101.068853ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:56:40.763476Z","caller":"traceutil/trace.go:171","msg":"trace[554546508] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1192; }","duration":"117.450506ms","start":"2026-05-26T08:56:40.646015Z","end":"2026-05-26T08:56:40.763466Z","steps":["trace[554546508] 'agreement among raft nodes before linearized reading' (duration: 117.308773ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.10939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:56:43.61037Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261661,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-26T08:56:43.937555Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471003423s","expected-duration":"1s"} {"level":"info","ts":"2026-05-26T08:56:43.938059Z","caller":"traceutil/trace.go:171","msg":"trace[1312062718] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"1.328744349s","start":"2026-05-26T08:56:42.609288Z","end":"2026-05-26T08:56:43.938033Z","steps":["trace[1312062718] 'read index received' (duration: 1.328393142s)","trace[1312062718] 'applied index is now lower than readState.Index' (duration: 349.837µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:56:43.938405Z","caller":"traceutil/trace.go:171","msg":"trace[107433892] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"1.471955744s","start":"2026-05-26T08:56:42.466434Z","end":"2026-05-26T08:56:43.93839Z","steps":["trace[107433892] 'process raft request' (duration: 1.471406972s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.938523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:42.466404Z","time spent":"1.472038765s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:56:43.938611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170383561s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:56:43.938668Z","caller":"traceutil/trace.go:171","msg":"trace[796023664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.170477693s","start":"2026-05-26T08:56:42.768173Z","end":"2026-05-26T08:56:43.938651Z","steps":["trace[796023664] 'agreement among raft nodes before linearized reading' (duration: 1.170303049s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.93871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:42.768154Z","time spent":"1.170544655s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T08:56:43.938698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.67731ms","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-05-26T08:56:43.93877Z","caller":"traceutil/trace.go:171","msg":"trace[1202825821] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:1195; }","duration":"150.803282ms","start":"2026-05-26T08:56:43.787942Z","end":"2026-05-26T08:56:43.938745Z","steps":["trace[1202825821] 'agreement among raft nodes before linearized reading' (duration: 150.649359ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.938879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.170611806s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-26T08:56:43.938881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.329582978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:56:43.938946Z","caller":"traceutil/trace.go:171","msg":"trace[1035300965] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1195; }","duration":"1.329703261s","start":"2026-05-26T08:56:42.609227Z","end":"2026-05-26T08:56:43.938931Z","steps":["trace[1035300965] 'agreement among raft nodes before linearized reading' (duration: 1.329514447s)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:56:43.938938Z","caller":"traceutil/trace.go:171","msg":"trace[547654169] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.170715108s","start":"2026-05-26T08:56:42.768207Z","end":"2026-05-26T08:56:43.938922Z","steps":["trace[547654169] 'agreement among raft nodes before linearized reading' (duration: 1.170577615s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.938973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:42.609205Z","time spent":"1.329763172s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:56:43.938984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:42.768183Z","time spent":"1.17078854s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:56:43.939128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.167529497s","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-05-26T08:56:43.93917Z","caller":"traceutil/trace.go:171","msg":"trace[1989173965] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1195; }","duration":"1.167588418s","start":"2026-05-26T08:56:42.771569Z","end":"2026-05-26T08:56:43.939157Z","steps":["trace[1989173965] 'agreement among raft nodes before linearized reading' (duration: 1.167120478s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:43.939231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:42.771561Z","time spent":"1.16765728s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-26T08:56:44.38588Z","caller":"traceutil/trace.go:171","msg":"trace[1314378913] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"442.356931ms","start":"2026-05-26T08:56:43.943496Z","end":"2026-05-26T08:56:44.385853Z","steps":["trace[1314378913] 'process raft request' (duration: 442.148577ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:44.386097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:43.94347Z","time spent":"442.490624ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:44.387248Z","caller":"traceutil/trace.go:171","msg":"trace[889521066] transaction","detail":"{read_only:false; response_revision:1197; number_of_response:1; }","duration":"443.66959ms","start":"2026-05-26T08:56:43.943542Z","end":"2026-05-26T08:56:44.387212Z","steps":["trace[889521066] 'process raft request' (duration: 443.460395ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:44.387443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:43.943514Z","time spent":"443.825422ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:44.38776Z","caller":"traceutil/trace.go:171","msg":"trace[2091913760] transaction","detail":"{read_only:false; response_revision:1198; number_of_response:1; }","duration":"444.017527ms","start":"2026-05-26T08:56:43.943704Z","end":"2026-05-26T08:56:44.387722Z","steps":["trace[2091913760] 'process raft request' (duration: 443.361312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:44.387845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:43.943697Z","time spent":"444.104308ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:44.387889Z","caller":"traceutil/trace.go:171","msg":"trace[279868385] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"442.875302ms","start":"2026-05-26T08:56:43.944997Z","end":"2026-05-26T08:56:44.387872Z","steps":["trace[279868385] 'process raft request' (duration: 442.103105ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:44.387966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:43.94499Z","time spent":"442.933923ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:56:44.389099Z","caller":"traceutil/trace.go:171","msg":"trace[2049318349] linearizableReadLoop","detail":"{readStateIndex:1247; appliedIndex:1243; }","duration":"338.500136ms","start":"2026-05-26T08:56:44.050577Z","end":"2026-05-26T08:56:44.389077Z","steps":["trace[2049318349] 'read index received' (duration: 335.00697ms)","trace[2049318349] 'applied index is now lower than readState.Index' (duration: 3.491906ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:56:44.389411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.815933ms","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-05-26T08:56:44.389439Z","caller":"traceutil/trace.go:171","msg":"trace[1416294963] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1199; }","duration":"338.898594ms","start":"2026-05-26T08:56:44.050532Z","end":"2026-05-26T08:56:44.389431Z","steps":["trace[1416294963] 'agreement among raft nodes before linearized reading' (duration: 338.833263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:44.389464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:44.050513Z","time spent":"338.943865ms","remote":"127.0.0.1:36422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T08:56:44.389474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.704497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-26T08:56:44.389539Z","caller":"traceutil/trace.go:171","msg":"trace[1395630727] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1199; }","duration":"227.809289ms","start":"2026-05-26T08:56:44.161713Z","end":"2026-05-26T08:56:44.389522Z","steps":["trace[1395630727] 'agreement among raft nodes before linearized reading' (duration: 227.614405ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:57.080129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.903462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:56:57.080214Z","caller":"traceutil/trace.go:171","msg":"trace[2018643280] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:1240; }","duration":"189.021205ms","start":"2026-05-26T08:56:56.891174Z","end":"2026-05-26T08:56:57.080195Z","steps":["trace[2018643280] 'count revisions from in-memory index tree' (duration: 188.81916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:57.08013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"320.587779ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:56:57.080316Z","caller":"traceutil/trace.go:171","msg":"trace[126870195] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:1240; }","duration":"320.810604ms","start":"2026-05-26T08:56:56.759475Z","end":"2026-05-26T08:56:57.080285Z","steps":["trace[126870195] 'count revisions from in-memory index tree' (duration: 320.436876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:56:57.08041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:56:56.759454Z","time spent":"320.925157ms","remote":"127.0.0.1:36556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-26T08:56:57.080573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.417179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:56:57.080616Z","caller":"traceutil/trace.go:171","msg":"trace[427725838] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1240; }","duration":"151.022342ms","start":"2026-05-26T08:56:56.929582Z","end":"2026-05-26T08:56:57.080605Z","steps":["trace[427725838] 'count revisions from in-memory index tree' (duration: 150.288555ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:00.349537Z","caller":"traceutil/trace.go:171","msg":"trace[613284194] transaction","detail":"{read_only:false; response_revision:1270; number_of_response:1; }","duration":"110.461631ms","start":"2026-05-26T08:57:00.239052Z","end":"2026-05-26T08:57:00.349513Z","steps":["trace[613284194] 'process raft request' (duration: 110.313028ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:01.99891Z","caller":"traceutil/trace.go:171","msg":"trace[194685611] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"116.155278ms","start":"2026-05-26T08:57:01.88245Z","end":"2026-05-26T08:57:01.998606Z","steps":["trace[194685611] 'process raft request' (duration: 115.935703ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.661423Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261882,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:57:02.696686Z","caller":"traceutil/trace.go:171","msg":"trace[1838958929] linearizableReadLoop","detail":"{readStateIndex:1351; appliedIndex:1350; }","duration":"535.749318ms","start":"2026-05-26T08:57:02.160906Z","end":"2026-05-26T08:57:02.696655Z","steps":["trace[1838958929] 'read index received' (duration: 535.522073ms)","trace[1838958929] 'applied index is now lower than readState.Index' (duration: 226.375µs)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:02.696812Z","caller":"traceutil/trace.go:171","msg":"trace[2144866926] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"591.337926ms","start":"2026-05-26T08:57:02.105463Z","end":"2026-05-26T08:57:02.696801Z","steps":["trace[2144866926] 'process raft request' (duration: 591.02781ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.697057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.738936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-26T08:57:02.697268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.629486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-05-26T08:57:02.697272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.345961ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/default/default\" ","response":"range_response_count:1 size:171"} {"level":"warn","ts":"2026-05-26T08:57:02.697308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.752673ms","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-05-26T08:57:02.697363Z","caller":"traceutil/trace.go:171","msg":"trace[1627156990] range","detail":"{range_begin:/registry/serviceaccounts/default/default; range_end:; response_count:1; response_revision:1299; }","duration":"536.484914ms","start":"2026-05-26T08:57:02.160855Z","end":"2026-05-26T08:57:02.69734Z","steps":["trace[1627156990] 'agreement among raft nodes before linearized reading' (duration: 536.29541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.697347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.144413ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:57:02.697399Z","caller":"traceutil/trace.go:171","msg":"trace[1949465908] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1299; }","duration":"118.860206ms","start":"2026-05-26T08:57:02.57852Z","end":"2026-05-26T08:57:02.697381Z","steps":["trace[1949465908] 'agreement among raft nodes before linearized reading' (duration: 118.657761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.69742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.160838Z","time spent":"536.572135ms","remote":"127.0.0.1:40854","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":194,"request content":"key:\"/registry/serviceaccounts/default/default\" "} {"level":"info","ts":"2026-05-26T08:57:02.697426Z","caller":"traceutil/trace.go:171","msg":"trace[946756991] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1299; }","duration":"176.281465ms","start":"2026-05-26T08:57:02.521132Z","end":"2026-05-26T08:57:02.697414Z","steps":["trace[946756991] 'agreement among raft nodes before linearized reading' (duration: 176.083611ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.69747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.271668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:57:02.697527Z","caller":"traceutil/trace.go:171","msg":"trace[454065017] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1299; }","duration":"121.356651ms","start":"2026-05-26T08:57:02.576158Z","end":"2026-05-26T08:57:02.697514Z","steps":["trace[454065017] 'agreement among raft nodes before linearized reading' (duration: 121.220178ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.697576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.105429Z","time spent":"591.416928ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:02.697345Z","caller":"traceutil/trace.go:171","msg":"trace[341037545] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"126.732059ms","start":"2026-05-26T08:57:02.570594Z","end":"2026-05-26T08:57:02.697326Z","steps":["trace[341037545] 'agreement among raft nodes before linearized reading' (duration: 126.508734ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:02.697785Z","caller":"traceutil/trace.go:171","msg":"trace[1862620885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1299; }","duration":"285.884719ms","start":"2026-05-26T08:57:02.411247Z","end":"2026-05-26T08:57:02.697131Z","steps":["trace[1862620885] 'agreement among raft nodes before linearized reading' (duration: 285.743736ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:02.697845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.587727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T08:57:02.698214Z","caller":"traceutil/trace.go:171","msg":"trace[1831566851] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1299; }","duration":"121.980865ms","start":"2026-05-26T08:57:02.576191Z","end":"2026-05-26T08:57:02.698171Z","steps":["trace[1831566851] 'agreement among raft nodes before linearized reading' (duration: 120.85695ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:02.910927Z","caller":"traceutil/trace.go:171","msg":"trace[187913469] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"209.163197ms","start":"2026-05-26T08:57:02.701731Z","end":"2026-05-26T08:57:02.910894Z","steps":["trace[187913469] 'process raft request' (duration: 137.979775ms)","trace[187913469] 'compare' (duration: 71.034668ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:03.480678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.762344ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:57:03.480877Z","caller":"traceutil/trace.go:171","msg":"trace[664501822] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"778.313017ms","start":"2026-05-26T08:57:02.702525Z","end":"2026-05-26T08:57:03.480838Z","steps":["trace[664501822] 'process raft request' (duration: 368.819048ms)","trace[664501822] 'compare' (duration: 408.581869ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:03.480914Z","caller":"traceutil/trace.go:171","msg":"trace[1418112115] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"777.933849ms","start":"2026-05-26T08:57:02.702968Z","end":"2026-05-26T08:57:03.480902Z","steps":["trace[1418112115] 'process raft request' (duration: 777.872158ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:03.480957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.702952Z","time spent":"777.98646ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:03.481014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.702502Z","time spent":"778.43637ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:03.481072Z","caller":"traceutil/trace.go:171","msg":"trace[1303577581] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"778.45147ms","start":"2026-05-26T08:57:02.702615Z","end":"2026-05-26T08:57:03.481067Z","steps":["trace[1303577581] 'process raft request' (duration: 778.187154ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:03.4811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.702597Z","time spent":"778.486861ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:03.481174Z","caller":"traceutil/trace.go:171","msg":"trace[502507762] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"776.522627ms","start":"2026-05-26T08:57:02.704647Z","end":"2026-05-26T08:57:03.48117Z","steps":["trace[502507762] 'process raft request' (duration: 776.21011ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:03.481198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:02.704612Z","time spent":"776.573768ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:05.139571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.791316ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-26T08:57:05.139672Z","caller":"traceutil/trace.go:171","msg":"trace[1538567995] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1357; }","duration":"728.814681ms","start":"2026-05-26T08:57:04.410838Z","end":"2026-05-26T08:57:05.139653Z","steps":["trace[1538567995] 'read index received' (duration: 90.946255ms)","trace[1538567995] 'applied index is now lower than readState.Index' (duration: 637.867066ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:05.140115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.265741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:05.140148Z","caller":"traceutil/trace.go:171","msg":"trace[1532775974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1305; }","duration":"729.349943ms","start":"2026-05-26T08:57:04.410788Z","end":"2026-05-26T08:57:05.140138Z","steps":["trace[1532775974] 'agreement among raft nodes before linearized reading' (duration: 729.278761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:05.14018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:04.410761Z","time spent":"729.412004ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:57:05.207263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"624.822661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T08:57:05.207329Z","caller":"traceutil/trace.go:171","msg":"trace[1575776856] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"504.719068ms","start":"2026-05-26T08:57:04.702577Z","end":"2026-05-26T08:57:05.207296Z","steps":["trace[1575776856] 'process raft request' (duration: 504.432121ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:05.207388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.577825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:57:05.207419Z","caller":"traceutil/trace.go:171","msg":"trace[1857052184] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1306; }","duration":"292.646487ms","start":"2026-05-26T08:57:04.914764Z","end":"2026-05-26T08:57:05.20741Z","steps":["trace[1857052184] 'agreement among raft nodes before linearized reading' (duration: 292.546205ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:05.207412Z","caller":"traceutil/trace.go:171","msg":"trace[1578117195] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1306; }","duration":"624.999085ms","start":"2026-05-26T08:57:04.582353Z","end":"2026-05-26T08:57:05.207352Z","steps":["trace[1578117195] 'agreement among raft nodes before linearized reading' (duration: 624.819981ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:05.207485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:04.702558Z","time spent":"504.853501ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:05.207495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:04.582328Z","time spent":"625.152849ms","remote":"127.0.0.1:36520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true "} {"level":"warn","ts":"2026-05-26T08:57:05.20769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.315824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2721"} {"level":"info","ts":"2026-05-26T08:57:05.208266Z","caller":"traceutil/trace.go:171","msg":"trace[1108198578] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1306; }","duration":"253.866477ms","start":"2026-05-26T08:57:04.954292Z","end":"2026-05-26T08:57:05.208159Z","steps":["trace[1108198578] 'agreement among raft nodes before linearized reading' (duration: 252.902635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:05.872865Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6232311609504261900,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-26T08:57:06.026215Z","caller":"traceutil/trace.go:171","msg":"trace[2071088079] linearizableReadLoop","detail":"{readStateIndex:1360; appliedIndex:1359; }","duration":"654.226636ms","start":"2026-05-26T08:57:05.371963Z","end":"2026-05-26T08:57:06.02619Z","steps":["trace[2071088079] 'read index received' (duration: 650.932282ms)","trace[2071088079] 'applied index is now lower than readState.Index' (duration: 3.293214ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:06.026451Z","caller":"traceutil/trace.go:171","msg":"trace[1284896201] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"815.052282ms","start":"2026-05-26T08:57:05.211387Z","end":"2026-05-26T08:57:06.026439Z","steps":["trace[1284896201] 'process raft request' (duration: 811.592716ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.027448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.127688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:06.027586Z","caller":"traceutil/trace.go:171","msg":"trace[1497017926] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"615.311011ms","start":"2026-05-26T08:57:05.412261Z","end":"2026-05-26T08:57:06.027572Z","steps":["trace[1497017926] 'agreement among raft nodes before linearized reading' (duration: 615.142248ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.027647Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.41224Z","time spent":"615.386944ms","remote":"127.0.0.1:40646","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:57:06.027643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.413982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/clienttrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:06.02773Z","caller":"traceutil/trace.go:171","msg":"trace[1327100668] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/clienttrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/clienttrafficpolicies0; response_count:0; response_revision:1307; }","duration":"235.540074ms","start":"2026-05-26T08:57:05.79217Z","end":"2026-05-26T08:57:06.02771Z","steps":["trace[1327100668] 'agreement among raft nodes before linearized reading' (duration: 235.438432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.027793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.107327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-26T08:57:06.027838Z","caller":"traceutil/trace.go:171","msg":"trace[1346799119] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"542.190829ms","start":"2026-05-26T08:57:05.485635Z","end":"2026-05-26T08:57:06.027826Z","steps":["trace[1346799119] 'agreement among raft nodes before linearized reading' (duration: 542.056416ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.027835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.617208ms","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-05-26T08:57:06.027879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.485611Z","time spent":"542.25991ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-26T08:57:06.027646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.674467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:06.027926Z","caller":"traceutil/trace.go:171","msg":"trace[1416398291] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1307; }","duration":"542.755201ms","start":"2026-05-26T08:57:05.485152Z","end":"2026-05-26T08:57:06.027907Z","steps":["trace[1416398291] 'agreement among raft nodes before linearized reading' (duration: 542.500985ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.027979Z","caller":"traceutil/trace.go:171","msg":"trace[266196053] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1307; }","duration":"656.052266ms","start":"2026-05-26T08:57:05.37191Z","end":"2026-05-26T08:57:06.027963Z","steps":["trace[266196053] 'agreement among raft nodes before linearized reading' (duration: 655.683108ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.027989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.485133Z","time spent":"542.843183ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T08:57:06.027574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.81162ms","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-05-26T08:57:06.027955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.211362Z","time spent":"815.759458ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:06.028038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.371873Z","time spent":"656.148777ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-26T08:57:06.028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.771081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:57:06.028051Z","caller":"traceutil/trace.go:171","msg":"trace[822024438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1307; }","duration":"542.341581ms","start":"2026-05-26T08:57:05.485699Z","end":"2026-05-26T08:57:06.02804Z","steps":["trace[822024438] 'agreement among raft nodes before linearized reading' (duration: 541.725028ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.028171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.485675Z","time spent":"542.485835ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-26T08:57:06.02825Z","caller":"traceutil/trace.go:171","msg":"trace[956249051] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1307; }","duration":"543.056917ms","start":"2026-05-26T08:57:05.485097Z","end":"2026-05-26T08:57:06.028154Z","steps":["trace[956249051] 'agreement among raft nodes before linearized reading' (duration: 542.552386ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.028359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:05.485083Z","time spent":"543.261122ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:57:06.641707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.524911ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:06.641854Z","caller":"traceutil/trace.go:171","msg":"trace[2002703987] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1307; }","duration":"135.702515ms","start":"2026-05-26T08:57:06.50613Z","end":"2026-05-26T08:57:06.641832Z","steps":["trace[2002703987] 'range keys from in-memory index tree' (duration: 135.511441ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.642079Z","caller":"traceutil/trace.go:171","msg":"trace[670661780] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"609.448715ms","start":"2026-05-26T08:57:06.03261Z","end":"2026-05-26T08:57:06.642059Z","steps":["trace[670661780] 'process raft request' (duration: 524.277776ms)","trace[670661780] 'compare' (duration: 84.695827ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:06.642247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.032599Z","time spent":"609.555257ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:06.766628Z","caller":"traceutil/trace.go:171","msg":"trace[1359001220] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"733.706859ms","start":"2026-05-26T08:57:06.032894Z","end":"2026-05-26T08:57:06.766601Z","steps":["trace[1359001220] 'process raft request' (duration: 719.951607ms)","trace[1359001220] 'compare' (duration: 13.627639ms)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:06.766885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.032888Z","time spent":"733.816511ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:06.767111Z","caller":"traceutil/trace.go:171","msg":"trace[396485313] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"733.778261ms","start":"2026-05-26T08:57:06.033316Z","end":"2026-05-26T08:57:06.767094Z","steps":["trace[396485313] 'process raft request' (duration: 733.581937ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.767147Z","caller":"traceutil/trace.go:171","msg":"trace[1846657507] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"733.913514ms","start":"2026-05-26T08:57:06.033216Z","end":"2026-05-26T08:57:06.767129Z","steps":["trace[1846657507] 'process raft request' (duration: 733.345711ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.76717Z","caller":"traceutil/trace.go:171","msg":"trace[1345933940] linearizableReadLoop","detail":"{readStateIndex:1367; appliedIndex:1360; }","duration":"357.838127ms","start":"2026-05-26T08:57:06.409306Z","end":"2026-05-26T08:57:06.767144Z","steps":["trace[1345933940] 'read index received' (duration: 147.592697ms)","trace[1345933940] 'applied index is now lower than readState.Index' (duration: 210.24452ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:06.767253Z","caller":"traceutil/trace.go:171","msg":"trace[750655998] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"382.344544ms","start":"2026-05-26T08:57:06.384897Z","end":"2026-05-26T08:57:06.767241Z","steps":["trace[750655998] 'process raft request' (duration: 382.16387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.767201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.033293Z","time spent":"733.862772ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:06.767289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.033208Z","time spent":"733.966855ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:06.767357Z","caller":"traceutil/trace.go:171","msg":"trace[1398181102] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"733.059954ms","start":"2026-05-26T08:57:06.034286Z","end":"2026-05-26T08:57:06.767346Z","steps":["trace[1398181102] 'process raft request' (duration: 732.738387ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.767411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.034278Z","time spent":"733.107765ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:06.767427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.599973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-26T08:57:06.767307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.384865Z","time spent":"382.416285ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:06.767444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"358.136793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:06.767468Z","caller":"traceutil/trace.go:171","msg":"trace[1693499880] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1314; }","duration":"239.703796ms","start":"2026-05-26T08:57:06.527754Z","end":"2026-05-26T08:57:06.767458Z","steps":["trace[1693499880] 'agreement among raft nodes before linearized reading' (duration: 239.602854ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.767492Z","caller":"traceutil/trace.go:171","msg":"trace[86514135] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1314; }","duration":"358.218365ms","start":"2026-05-26T08:57:06.409261Z","end":"2026-05-26T08:57:06.767479Z","steps":["trace[86514135] 'agreement among raft nodes before linearized reading' (duration: 358.119733ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:06.767509Z","caller":"traceutil/trace.go:171","msg":"trace[516137031] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"373.251235ms","start":"2026-05-26T08:57:06.394249Z","end":"2026-05-26T08:57:06.7675Z","steps":["trace[516137031] 'process raft request' (duration: 372.845486ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:06.767554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.394226Z","time spent":"373.303725ms","remote":"127.0.0.1:40828","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6261,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:06.767558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:06.409247Z","time spent":"358.293896ms","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:57:07.03278Z","caller":"traceutil/trace.go:171","msg":"trace[1264683874] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"182.113443ms","start":"2026-05-26T08:57:06.850647Z","end":"2026-05-26T08:57:07.03276Z","steps":["trace[1264683874] 'process raft request' (duration: 181.980461ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.383259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.246777608s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:57:08.38344Z","caller":"traceutil/trace.go:171","msg":"trace[887359037] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"1.269520297s","start":"2026-05-26T08:57:07.113909Z","end":"2026-05-26T08:57:08.383429Z","steps":["trace[887359037] 'process raft request' (duration: 1.269499707s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.383482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.11388Z","time spent":"1.269579129s","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":780,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:57:08.383595Z","caller":"traceutil/trace.go:171","msg":"trace[124943807] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"1.325473384s","start":"2026-05-26T08:57:07.058116Z","end":"2026-05-26T08:57:08.383589Z","steps":["trace[124943807] 'process raft request' (duration: 78.317007ms)","trace[124943807] 'compare' (duration: 1.242552957s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:08.383658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.0581Z","time spent":"1.325506405s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:08.383731Z","caller":"traceutil/trace.go:171","msg":"trace[1495790821] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"1.324271938s","start":"2026-05-26T08:57:07.059453Z","end":"2026-05-26T08:57:08.383725Z","steps":["trace[1495790821] 'process raft request' (duration: 1.32392834s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.383751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.059445Z","time spent":"1.324295858s","remote":"127.0.0.1:40714","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":869,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-26T08:57:08.464736Z","caller":"traceutil/trace.go:171","msg":"trace[254703328] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"1.243263082s","start":"2026-05-26T08:57:07.221456Z","end":"2026-05-26T08:57:08.464719Z","steps":["trace[254703328] 'process raft request' (duration: 1.243039407s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.464907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.221432Z","time spent":"1.243392125s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:08.465235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.054741938s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:08.465277Z","caller":"traceutil/trace.go:171","msg":"trace[1243150010] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1320; }","duration":"1.054823139s","start":"2026-05-26T08:57:07.410445Z","end":"2026-05-26T08:57:08.465268Z","steps":["trace[1243150010] 'agreement among raft nodes before linearized reading' (duration: 1.054758558s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.465305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.410422Z","time spent":"1.054877871s","remote":"127.0.0.1:40644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-26T08:57:08.465157Z","caller":"traceutil/trace.go:171","msg":"trace[1538093919] linearizableReadLoop","detail":"{readStateIndex:1373; appliedIndex:1369; }","duration":"1.054160005s","start":"2026-05-26T08:57:07.410498Z","end":"2026-05-26T08:57:08.464658Z","steps":["trace[1538093919] 'read index received' (duration: 68.856629ms)","trace[1538093919] 'applied index is now lower than readState.Index' (duration: 985.302386ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:08.499964Z","caller":"traceutil/trace.go:171","msg":"trace[889515405] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"111.922775ms","start":"2026-05-26T08:57:08.388018Z","end":"2026-05-26T08:57:08.499941Z","steps":["trace[889515405] 'process raft request' (duration: 101.365873ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.500198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"466.456059ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:57:08.500243Z","caller":"traceutil/trace.go:171","msg":"trace[938934992] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1321; }","duration":"466.51161ms","start":"2026-05-26T08:57:08.033719Z","end":"2026-05-26T08:57:08.500231Z","steps":["trace[938934992] 'agreement among raft nodes before linearized reading' (duration: 466.326176ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.500282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:08.033641Z","time spent":"466.633673ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-26T08:57:08.500491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.222042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-26T08:57:08.500547Z","caller":"traceutil/trace.go:171","msg":"trace[849116995] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1321; }","duration":"585.317084ms","start":"2026-05-26T08:57:07.915218Z","end":"2026-05-26T08:57:08.500535Z","steps":["trace[849116995] 'agreement among raft nodes before linearized reading' (duration: 585.179412ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.500586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.915199Z","time spent":"585.377886ms","remote":"127.0.0.1:40738","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":17,"response size":30,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-26T08:57:08.500769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"716.805328ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:08.500883Z","caller":"traceutil/trace.go:171","msg":"trace[1784385171] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1321; }","duration":"716.982602ms","start":"2026-05-26T08:57:07.78387Z","end":"2026-05-26T08:57:08.500852Z","steps":["trace[1784385171] 'agreement among raft nodes before linearized reading' (duration: 716.144954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:08.500938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:07.783848Z","time spent":"717.081144ms","remote":"127.0.0.1:45418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-05-26T08:57:18.337429Z","caller":"traceutil/trace.go:171","msg":"trace[727496926] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"694.190882ms","start":"2026-05-26T08:57:17.64321Z","end":"2026-05-26T08:57:18.337401Z","steps":["trace[727496926] 'process raft request' (duration: 694.012418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:18.337598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:17.64318Z","time spent":"694.339174ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:19.654345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.074775427s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-26T08:57:19.654449Z","caller":"traceutil/trace.go:171","msg":"trace[2113829342] linearizableReadLoop","detail":"{readStateIndex:1450; appliedIndex:1449; }","duration":"1.096276929s","start":"2026-05-26T08:57:18.558156Z","end":"2026-05-26T08:57:19.654433Z","steps":["trace[2113829342] 'read index received' (duration: 21.055101ms)","trace[2113829342] 'applied index is now lower than readState.Index' (duration: 1.075220648s)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:19.654534Z","caller":"traceutil/trace.go:171","msg":"trace[1013208702] transaction","detail":"{read_only:false; response_revision:1395; number_of_response:1; }","duration":"1.151704655s","start":"2026-05-26T08:57:18.502818Z","end":"2026-05-26T08:57:19.654522Z","steps":["trace[1013208702] 'process raft request' (duration: 76.466597ms)","trace[1013208702] 'compare' (duration: 1.074409059s)"],"step_count":2} {"level":"warn","ts":"2026-05-26T08:57:19.654596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.502782Z","time spent":"1.151782877s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:19.654972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.303021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-26T08:57:19.655081Z","caller":"traceutil/trace.go:171","msg":"trace[2143160354] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1395; }","duration":"874.458885ms","start":"2026-05-26T08:57:18.780603Z","end":"2026-05-26T08:57:19.655062Z","steps":["trace[2143160354] 'agreement among raft nodes before linearized reading' (duration: 874.208589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.655126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.780581Z","time spent":"874.537387ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-26T08:57:19.655229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"827.089726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-05-26T08:57:19.655245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"506.154109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.4\" ","response":"range_response_count:1 size:131"} {"level":"info","ts":"2026-05-26T08:57:19.655314Z","caller":"traceutil/trace.go:171","msg":"trace[649789662] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1395; }","duration":"827.215039ms","start":"2026-05-26T08:57:18.82808Z","end":"2026-05-26T08:57:19.655295Z","steps":["trace[649789662] 'agreement among raft nodes before linearized reading' (duration: 826.990634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.655333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.666439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-05-26T08:57:19.655325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097174989s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-26T08:57:19.655362Z","caller":"traceutil/trace.go:171","msg":"trace[434934059] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1395; }","duration":"365.73662ms","start":"2026-05-26T08:57:19.289617Z","end":"2026-05-26T08:57:19.655354Z","steps":["trace[434934059] 'agreement among raft nodes before linearized reading' (duration: 365.607797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.65536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.828069Z","time spent":"827.2822ms","remote":"127.0.0.1:40920","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-05-26T08:57:19.655374Z","caller":"traceutil/trace.go:171","msg":"trace[2090898373] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1395; }","duration":"1.097266351s","start":"2026-05-26T08:57:18.558094Z","end":"2026-05-26T08:57:19.655361Z","steps":["trace[2090898373] 'agreement among raft nodes before linearized reading' (duration: 1.097092797s)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.655397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.289594Z","time spent":"365.795722ms","remote":"127.0.0.1:41186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":166,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-26T08:57:19.655409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.55807Z","time spent":"1.097332142s","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-05-26T08:57:19.655472Z","caller":"traceutil/trace.go:171","msg":"trace[1475723891] range","detail":"{range_begin:/registry/masterleases/199.204.45.4; range_end:; response_count:1; response_revision:1395; }","duration":"506.272982ms","start":"2026-05-26T08:57:19.149031Z","end":"2026-05-26T08:57:19.655304Z","steps":["trace[1475723891] 'agreement among raft nodes before linearized reading' (duration: 505.905334ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.655428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.690662ms","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-05-26T08:57:19.655579Z","caller":"traceutil/trace.go:171","msg":"trace[1799284817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1395; }","duration":"792.892407ms","start":"2026-05-26T08:57:18.862677Z","end":"2026-05-26T08:57:19.655569Z","steps":["trace[1799284817] 'agreement among raft nodes before linearized reading' (duration: 792.468968ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.655648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.862635Z","time spent":"793.001019ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-26T08:57:19.655706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.148994Z","time spent":"506.703721ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":154,"request content":"key:\"/registry/masterleases/199.204.45.4\" "} {"level":"warn","ts":"2026-05-26T08:57:19.655931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.34155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-26T08:57:19.656155Z","caller":"traceutil/trace.go:171","msg":"trace[404752915] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1395; }","duration":"826.604415ms","start":"2026-05-26T08:57:18.829531Z","end":"2026-05-26T08:57:19.656135Z","steps":["trace[404752915] 'agreement among raft nodes before linearized reading' (duration: 825.425129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:19.65627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:18.829511Z","time spent":"826.744059ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-26T08:57:20.064439Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.657251Z","time spent":"407.181498ms","remote":"127.0.0.1:40666","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-26T08:57:20.087337Z","caller":"traceutil/trace.go:171","msg":"trace[1968868877] transaction","detail":"{read_only:false; response_revision:1397; number_of_response:1; }","duration":"427.137706ms","start":"2026-05-26T08:57:19.660172Z","end":"2026-05-26T08:57:20.087309Z","steps":["trace[1968868877] 'process raft request' (duration: 427.049224ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:20.087376Z","caller":"traceutil/trace.go:171","msg":"trace[406052828] transaction","detail":"{read_only:false; response_revision:1396; number_of_response:1; }","duration":"427.77762ms","start":"2026-05-26T08:57:19.659572Z","end":"2026-05-26T08:57:20.087349Z","steps":["trace[406052828] 'process raft request' (duration: 427.452753ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.087505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.660159Z","time spent":"427.256908ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:20.087516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.659552Z","time spent":"427.887232ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:20.087662Z","caller":"traceutil/trace.go:171","msg":"trace[1352788839] transaction","detail":"{read_only:false; response_revision:1398; number_of_response:1; }","duration":"427.371101ms","start":"2026-05-26T08:57:19.660267Z","end":"2026-05-26T08:57:20.087638Z","steps":["trace[1352788839] 'process raft request' (duration: 427.003613ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.087821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.660259Z","time spent":"427.476723ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-26T08:57:20.087953Z","caller":"traceutil/trace.go:171","msg":"trace[1377021495] linearizableReadLoop","detail":"{readStateIndex:1456; appliedIndex:1450; }","duration":"257.728641ms","start":"2026-05-26T08:57:19.83021Z","end":"2026-05-26T08:57:20.087939Z","steps":["trace[1377021495] 'read index received' (duration: 178.595956ms)","trace[1377021495] 'applied index is now lower than readState.Index' (duration: 79.131205ms)"],"step_count":2} {"level":"info","ts":"2026-05-26T08:57:20.087959Z","caller":"traceutil/trace.go:171","msg":"trace[1466925321] transaction","detail":"{read_only:false; response_revision:1400; number_of_response:1; }","duration":"427.230277ms","start":"2026-05-26T08:57:19.660715Z","end":"2026-05-26T08:57:20.087945Z","steps":["trace[1466925321] 'process raft request' (duration: 427.064934ms)"],"step_count":1} {"level":"info","ts":"2026-05-26T08:57:20.087968Z","caller":"traceutil/trace.go:171","msg":"trace[275867581] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"427.35582ms","start":"2026-05-26T08:57:19.660591Z","end":"2026-05-26T08:57:20.087947Z","steps":["trace[275867581] 'process raft request' (duration: 426.940951ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.088018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.660706Z","time spent":"427.283348ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:20.088064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:19.660581Z","time spent":"427.436082ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-26T08:57:20.088148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.929135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:20.088237Z","caller":"traceutil/trace.go:171","msg":"trace[970111312] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:1400; }","duration":"258.068228ms","start":"2026-05-26T08:57:19.830157Z","end":"2026-05-26T08:57:20.088225Z","steps":["trace[970111312] 'agreement among raft nodes before linearized reading' (duration: 257.886244ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.095228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.541637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-05-26T08:57:20.095361Z","caller":"traceutil/trace.go:171","msg":"trace[762701597] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1401; }","duration":"127.716321ms","start":"2026-05-26T08:57:19.967619Z","end":"2026-05-26T08:57:20.095336Z","steps":["trace[762701597] 'agreement among raft nodes before linearized reading' (duration: 127.462275ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.676276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.421957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-26T08:57:20.676357Z","caller":"traceutil/trace.go:171","msg":"trace[2062958273] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1402; }","duration":"263.56739ms","start":"2026-05-26T08:57:20.412771Z","end":"2026-05-26T08:57:20.676338Z","steps":["trace[2062958273] 'range keys from in-memory index tree' (duration: 263.342665ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.676639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.28464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-05-26T08:57:20.676676Z","caller":"traceutil/trace.go:171","msg":"trace[2125851943] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1402; }","duration":"334.362802ms","start":"2026-05-26T08:57:20.342304Z","end":"2026-05-26T08:57:20.676667Z","steps":["trace[2125851943] 'range keys from in-memory index tree' (duration: 334.069646ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.676704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:20.342234Z","time spent":"334.461445ms","remote":"127.0.0.1:40920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-05-26T08:57:20.67645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.227118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2721"} {"level":"info","ts":"2026-05-26T08:57:20.676821Z","caller":"traceutil/trace.go:171","msg":"trace[1340105626] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1402; }","duration":"442.656157ms","start":"2026-05-26T08:57:20.234152Z","end":"2026-05-26T08:57:20.676808Z","steps":["trace[1340105626] 'range keys from in-memory index tree' (duration: 442.042882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-26T08:57:20.677035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-26T08:57:20.234131Z","time spent":"442.895693ms","remote":"127.0.0.1:40838","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2744,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "}