{"level":"warn","ts":"2026-04-11T17:48:49.446258Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:48:49.446347Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.246: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.246:2380","--initial-cluster=instance=https://199.204.45.246:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.246:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.246:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-11T17:48:49.446417Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-11T17:48:49.446431Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-11T17:48:49.446449Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:48:49.447185Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"]} {"level":"info","ts":"2026-04-11T17:48:49.447263Z","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.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.246:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-11T17:48:49.453594Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.143475ms"} {"level":"info","ts":"2026-04-11T17:48:49.461514Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ab97322ebdd79664","cluster-id":"87bc89380c3dc2c"} {"level":"info","ts":"2026-04-11T17:48:49.46184Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=()"} {"level":"info","ts":"2026-04-11T17:48:49.461863Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 0"} {"level":"info","ts":"2026-04-11T17:48:49.461878Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ab97322ebdd79664 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-11T17:48:49.461887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became follower at term 1"} {"level":"info","ts":"2026-04-11T17:48:49.461929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"warn","ts":"2026-04-11T17:48:49.468939Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-11T17:48:49.470755Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-11T17:48:49.471932Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-11T17:48:49.475099Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ab97322ebdd79664","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-11T17:48:49.475238Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ab97322ebdd79664","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-11T17:48:49.475232Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:48:49.475392Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:48:49.475414Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-11T17:48:49.476371Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 switched to configuration voters=(12364406478303893092)"} {"level":"info","ts":"2026-04-11T17:48:49.477012Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","added-peer-id":"ab97322ebdd79664","added-peer-peer-urls":["https://199.204.45.246:2380"]} {"level":"info","ts":"2026-04-11T17:48:49.481047Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-11T17:48:49.481288Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ab97322ebdd79664","initial-advertise-peer-urls":["https://199.204.45.246:2380"],"listen-peer-urls":["https://199.204.45.246:2380"],"advertise-client-urls":["https://199.204.45.246:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.246:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-11T17:48:49.481336Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-11T17:48:49.481329Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-11T17:48:49.481488Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.246:2380"} {"level":"info","ts":"2026-04-11T17:48:49.762917Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 is starting a new election at term 1"} {"level":"info","ts":"2026-04-11T17:48:49.76298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-11T17:48:49.763014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgPreVoteResp from ab97322ebdd79664 at term 1"} {"level":"info","ts":"2026-04-11T17:48:49.763034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became candidate at term 2"} {"level":"info","ts":"2026-04-11T17:48:49.763046Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 received MsgVoteResp from ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-11T17:48:49.763062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ab97322ebdd79664 became leader at term 2"} {"level":"info","ts":"2026-04-11T17:48:49.763077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ab97322ebdd79664 elected leader ab97322ebdd79664 at term 2"} {"level":"info","ts":"2026-04-11T17:48:49.764122Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:48:49.765001Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:48:49.765002Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ab97322ebdd79664","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.246:2379]}","request-path":"/0/members/ab97322ebdd79664/attributes","cluster-id":"87bc89380c3dc2c","publish-timeout":"7s"} {"level":"info","ts":"2026-04-11T17:48:49.765054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-11T17:48:49.765338Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"87bc89380c3dc2c","local-member-id":"ab97322ebdd79664","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:48:49.765501Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:48:49.765573Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-11T17:48:49.765489Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-11T17:48:49.76575Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-11T17:48:49.768463Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.246:2379"} {"level":"info","ts":"2026-04-11T17:48:49.769465Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-11T17:48:57.165467Z","caller":"traceutil/trace.go:171","msg":"trace[1916221895] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"134.005324ms","start":"2026-04-11T17:48:57.031426Z","end":"2026-04-11T17:48:57.165432Z","steps":["trace[1916221895] 'process raft request' (duration: 133.84165ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.841738Z","caller":"traceutil/trace.go:171","msg":"trace[2127622655] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"336.343602ms","start":"2026-04-11T17:48:59.505353Z","end":"2026-04-11T17:48:59.841696Z","steps":["trace[2127622655] 'process raft request' (duration: 336.127136ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.842516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.505318Z","time spent":"336.529588ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":899,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:48:59.938096Z","caller":"traceutil/trace.go:171","msg":"trace[1168643240] linearizableReadLoop","detail":"{readStateIndex:364; appliedIndex:361; }","duration":"406.019961ms","start":"2026-04-11T17:48:59.532044Z","end":"2026-04-11T17:48:59.938064Z","steps":["trace[1168643240] 'read index received' (duration: 309.471722ms)","trace[1168643240] 'applied index is now lower than readState.Index' (duration: 96.546999ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:48:59.938348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.271167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-04-11T17:48:59.938426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.058243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:48:59.938513Z","caller":"traceutil/trace.go:171","msg":"trace[1501206844] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:349; }","duration":"220.238478ms","start":"2026-04-11T17:48:59.71826Z","end":"2026-04-11T17:48:59.938498Z","steps":["trace[1501206844] 'agreement among raft nodes before linearized reading' (duration: 220.007252ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.938513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.476978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-11T17:48:59.938559Z","caller":"traceutil/trace.go:171","msg":"trace[1129687795] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:349; }","duration":"307.56372ms","start":"2026-04-11T17:48:59.630987Z","end":"2026-04-11T17:48:59.938551Z","steps":["trace[1129687795] 'agreement among raft nodes before linearized reading' (duration: 307.495228ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.938468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.594163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" ","response":"range_response_count:1 size:218"} {"level":"warn","ts":"2026-04-11T17:48:59.938609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.630966Z","time spent":"307.633642ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" "} {"level":"info","ts":"2026-04-11T17:48:59.938455Z","caller":"traceutil/trace.go:171","msg":"trace[1699775581] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:349; }","duration":"356.445002ms","start":"2026-04-11T17:48:59.581991Z","end":"2026-04-11T17:48:59.938436Z","steps":["trace[1699775581] 'agreement among raft nodes before linearized reading' (duration: 356.263807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:48:59.938789Z","caller":"traceutil/trace.go:171","msg":"trace[1813390953] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:349; }","duration":"256.948373ms","start":"2026-04-11T17:48:59.681816Z","end":"2026-04-11T17:48:59.938765Z","steps":["trace[1813390953] 'agreement among raft nodes before linearized reading' (duration: 256.572162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.93889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.581969Z","time spent":"356.799711ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/root-ca-cert-publisher\" "} {"level":"info","ts":"2026-04-11T17:48:59.938204Z","caller":"traceutil/trace.go:171","msg":"trace[351880428] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"432.464931ms","start":"2026-04-11T17:48:59.505671Z","end":"2026-04-11T17:48:59.938136Z","steps":["trace[351880428] 'process raft request' (duration: 411.407775ms)","trace[351880428] 'compare' (duration: 20.690536ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:48:59.93833Z","caller":"traceutil/trace.go:171","msg":"trace[842220872] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"427.931558ms","start":"2026-04-11T17:48:59.510245Z","end":"2026-04-11T17:48:59.938177Z","steps":["trace[842220872] 'process raft request' (duration: 427.724243ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.940504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.510229Z","time spent":"430.212479ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:48:59.941093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.50566Z","time spent":"433.380365ms","remote":"127.0.0.1:60798","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2094,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:48:59.941427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.377591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-11T17:48:59.941592Z","caller":"traceutil/trace.go:171","msg":"trace[997783645] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:349; }","duration":"409.452453ms","start":"2026-04-11T17:48:59.532007Z","end":"2026-04-11T17:48:59.94146Z","steps":["trace[997783645] 'agreement among raft nodes before linearized reading' (duration: 406.231696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:48:59.941668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:48:59.531989Z","time spent":"409.670059ms","remote":"127.0.0.1:60674","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":230,"request content":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" "} {"level":"info","ts":"2026-04-11T17:49:00.173083Z","caller":"traceutil/trace.go:171","msg":"trace[794740070] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"158.509213ms","start":"2026-04-11T17:49:00.014553Z","end":"2026-04-11T17:49:00.173062Z","steps":["trace[794740070] 'process raft request' (duration: 93.5677ms)","trace[794740070] 'compare' (duration: 64.692276ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:00.173313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.596114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-04-11T17:49:00.173401Z","caller":"traceutil/trace.go:171","msg":"trace[2006349708] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:359; }","duration":"158.759449ms","start":"2026-04-11T17:49:00.014622Z","end":"2026-04-11T17:49:00.173382Z","steps":["trace[2006349708] 'agreement among raft nodes before linearized reading' (duration: 158.542093ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:00.173007Z","caller":"traceutil/trace.go:171","msg":"trace[278336880] linearizableReadLoop","detail":"{readStateIndex:374; appliedIndex:373; }","duration":"158.292817ms","start":"2026-04-11T17:49:00.014686Z","end":"2026-04-11T17:49:00.172978Z","steps":["trace[278336880] 'read index received' (duration: 93.464198ms)","trace[278336880] 'applied index is now lower than readState.Index' (duration: 64.827649ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:00.17412Z","caller":"traceutil/trace.go:171","msg":"trace[1556507456] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"159.119229ms","start":"2026-04-11T17:49:00.014982Z","end":"2026-04-11T17:49:00.174101Z","steps":["trace[1556507456] 'process raft request' (duration: 158.80974ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:00.174251Z","caller":"traceutil/trace.go:171","msg":"trace[1656309438] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"158.631415ms","start":"2026-04-11T17:49:00.015601Z","end":"2026-04-11T17:49:00.174232Z","steps":["trace[1656309438] 'process raft request' (duration: 158.340367ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:00.174535Z","caller":"traceutil/trace.go:171","msg":"trace[1126601456] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"157.331981ms","start":"2026-04-11T17:49:00.017171Z","end":"2026-04-11T17:49:00.174503Z","steps":["trace[1126601456] 'process raft request' (duration: 156.839107ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:00.174208Z","caller":"traceutil/trace.go:171","msg":"trace[1052598220] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"154.777753ms","start":"2026-04-11T17:49:00.019398Z","end":"2026-04-11T17:49:00.174176Z","steps":["trace[1052598220] 'process raft request' (duration: 154.720471ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:00.174524Z","caller":"traceutil/trace.go:171","msg":"trace[916204387] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"155.965133ms","start":"2026-04-11T17:49:00.018512Z","end":"2026-04-11T17:49:00.174477Z","steps":["trace[916204387] 'process raft request' (duration: 155.541653ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:08.737004Z","caller":"traceutil/trace.go:171","msg":"trace[882180546] linearizableReadLoop","detail":"{readStateIndex:468; appliedIndex:467; }","duration":"191.054695ms","start":"2026-04-11T17:49:08.545909Z","end":"2026-04-11T17:49:08.736964Z","steps":["trace[882180546] 'read index received' (duration: 181.675223ms)","trace[882180546] 'applied index is now lower than readState.Index' (duration: 9.377672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:08.737296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.26029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:49:08.737434Z","caller":"traceutil/trace.go:171","msg":"trace[1882840221] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:448; }","duration":"191.508717ms","start":"2026-04-11T17:49:08.54585Z","end":"2026-04-11T17:49:08.737358Z","steps":["trace[1882840221] 'agreement among raft nodes before linearized reading' (duration: 191.215229ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:14.204005Z","caller":"traceutil/trace.go:171","msg":"trace[615902342] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"110.682958ms","start":"2026-04-11T17:49:14.093172Z","end":"2026-04-11T17:49:14.203855Z","steps":["trace[615902342] 'process raft request' (duration: 110.259966ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:14.284723Z","caller":"traceutil/trace.go:171","msg":"trace[1253847120] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"190.512111ms","start":"2026-04-11T17:49:14.09418Z","end":"2026-04-11T17:49:14.284692Z","steps":["trace[1253847120] 'process raft request' (duration: 187.893861ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:18.650796Z","caller":"traceutil/trace.go:171","msg":"trace[1370025906] linearizableReadLoop","detail":"{readStateIndex:491; appliedIndex:490; }","duration":"351.812198ms","start":"2026-04-11T17:49:18.298952Z","end":"2026-04-11T17:49:18.650764Z","steps":["trace[1370025906] 'read index received' (duration: 351.596162ms)","trace[1370025906] 'applied index is now lower than readState.Index' (duration: 214.496µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:18.650913Z","caller":"traceutil/trace.go:171","msg":"trace[235321222] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"416.828181ms","start":"2026-04-11T17:49:18.234035Z","end":"2026-04-11T17:49:18.650863Z","steps":["trace[235321222] 'process raft request' (duration: 416.548503ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:18.651003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.032053ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:49:18.651276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:18.234015Z","time spent":"417.116549ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:18.651321Z","caller":"traceutil/trace.go:171","msg":"trace[1480016444] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:470; }","duration":"352.396383ms","start":"2026-04-11T17:49:18.298873Z","end":"2026-04-11T17:49:18.65127Z","steps":["trace[1480016444] 'agreement among raft nodes before linearized reading' (duration: 352.044493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:18.651447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:18.298841Z","time spent":"352.557878ms","remote":"127.0.0.1:60726","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-04-11T17:49:19.828283Z","caller":"traceutil/trace.go:171","msg":"trace[622043755] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"384.631918ms","start":"2026-04-11T17:49:19.443624Z","end":"2026-04-11T17:49:19.828256Z","steps":["trace[622043755] 'process raft request' (duration: 383.952059ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:19.828423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:19.443605Z","time spent":"384.752692ms","remote":"127.0.0.1:60572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:49:22.065504Z","caller":"traceutil/trace.go:171","msg":"trace[195740088] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"188.764804ms","start":"2026-04-11T17:49:21.876699Z","end":"2026-04-11T17:49:22.065463Z","steps":["trace[195740088] 'process raft request' (duration: 188.559818ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.076994Z","caller":"traceutil/trace.go:171","msg":"trace[1128369054] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"192.412562ms","start":"2026-04-11T17:49:21.88453Z","end":"2026-04-11T17:49:22.076942Z","steps":["trace[1128369054] 'process raft request' (duration: 192.143904ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.07728Z","caller":"traceutil/trace.go:171","msg":"trace[873194777] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"169.315322ms","start":"2026-04-11T17:49:21.907951Z","end":"2026-04-11T17:49:22.077267Z","steps":["trace[873194777] 'process raft request' (duration: 169.058554ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077373Z","caller":"traceutil/trace.go:171","msg":"trace[752112174] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"189.682148ms","start":"2026-04-11T17:49:21.887681Z","end":"2026-04-11T17:49:22.077363Z","steps":["trace[752112174] 'process raft request' (duration: 189.178175ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077452Z","caller":"traceutil/trace.go:171","msg":"trace[769545247] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"187.074858ms","start":"2026-04-11T17:49:21.890365Z","end":"2026-04-11T17:49:22.07744Z","steps":["trace[769545247] 'process raft request' (duration: 186.559605ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077525Z","caller":"traceutil/trace.go:171","msg":"trace[1778585141] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"156.607961ms","start":"2026-04-11T17:49:21.920908Z","end":"2026-04-11T17:49:22.077516Z","steps":["trace[1778585141] 'process raft request' (duration: 156.302963ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077707Z","caller":"traceutil/trace.go:171","msg":"trace[826718384] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"126.597816ms","start":"2026-04-11T17:49:21.951087Z","end":"2026-04-11T17:49:22.077685Z","steps":["trace[826718384] 'process raft request' (duration: 126.314108ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077781Z","caller":"traceutil/trace.go:171","msg":"trace[537263382] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"161.586145ms","start":"2026-04-11T17:49:21.916184Z","end":"2026-04-11T17:49:22.07777Z","steps":["trace[537263382] 'process raft request' (duration: 160.913346ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077801Z","caller":"traceutil/trace.go:171","msg":"trace[2027562389] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"146.89374ms","start":"2026-04-11T17:49:21.930862Z","end":"2026-04-11T17:49:22.077756Z","steps":["trace[2027562389] 'process raft request' (duration: 146.48145ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.077818Z","caller":"traceutil/trace.go:171","msg":"trace[1065097017] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"113.512875ms","start":"2026-04-11T17:49:21.964266Z","end":"2026-04-11T17:49:22.077779Z","steps":["trace[1065097017] 'process raft request' (duration: 113.197506ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:22.078133Z","caller":"traceutil/trace.go:171","msg":"trace[158590843] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"161.616535ms","start":"2026-04-11T17:49:21.916259Z","end":"2026-04-11T17:49:22.077875Z","steps":["trace[158590843] 'process raft request' (duration: 160.891996ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:23.150196Z","caller":"traceutil/trace.go:171","msg":"trace[164481616] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"819.153554ms","start":"2026-04-11T17:49:22.331018Z","end":"2026-04-11T17:49:23.150171Z","steps":["trace[164481616] 'process raft request' (duration: 813.162423ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.150354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.330987Z","time spent":"819.285086ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:23.151401Z","caller":"traceutil/trace.go:171","msg":"trace[128150217] linearizableReadLoop","detail":"{readStateIndex:580; appliedIndex:577; }","duration":"474.713984ms","start":"2026-04-11T17:49:22.676662Z","end":"2026-04-11T17:49:23.151376Z","steps":["trace[128150217] 'read index received' (duration: 467.373386ms)","trace[128150217] 'applied index is now lower than readState.Index' (duration: 7.339838ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:23.15151Z","caller":"traceutil/trace.go:171","msg":"trace[1141968158] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"788.757268ms","start":"2026-04-11T17:49:22.362743Z","end":"2026-04-11T17:49:23.1515Z","steps":["trace[1141968158] 'process raft request' (duration: 788.573843ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.1516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.362711Z","time spent":"788.818921ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:49:23.151579Z","caller":"traceutil/trace.go:171","msg":"trace[748422222] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"810.900552ms","start":"2026-04-11T17:49:22.34062Z","end":"2026-04-11T17:49:23.151521Z","steps":["trace[748422222] 'process raft request' (duration: 810.496681ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.153494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.820711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:49:23.153733Z","caller":"traceutil/trace.go:171","msg":"trace[536546220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:554; }","duration":"476.970875ms","start":"2026-04-11T17:49:22.676595Z","end":"2026-04-11T17:49:23.153566Z","steps":["trace[536546220] 'agreement among raft nodes before linearized reading' (duration: 475.157876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.15373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.507894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:49:23.153743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.123771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:23.153792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.676563Z","time spent":"477.215571ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T17:49:23.153855Z","caller":"traceutil/trace.go:171","msg":"trace[100759648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"108.291575ms","start":"2026-04-11T17:49:23.045533Z","end":"2026-04-11T17:49:23.153824Z","steps":["trace[100759648] 'agreement among raft nodes before linearized reading' (duration: 107.923425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.153916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.34059Z","time spent":"811.104297ms","remote":"127.0.0.1:60502","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:23.153982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.491542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:49:23.154146Z","caller":"traceutil/trace.go:171","msg":"trace[1129869279] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:554; }","duration":"444.656348ms","start":"2026-04-11T17:49:22.709131Z","end":"2026-04-11T17:49:23.153787Z","steps":["trace[1129869279] 'agreement among raft nodes before linearized reading' (duration: 444.277738ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:23.154193Z","caller":"traceutil/trace.go:171","msg":"trace[137726107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:554; }","duration":"312.758209ms","start":"2026-04-11T17:49:22.841391Z","end":"2026-04-11T17:49:23.154149Z","steps":["trace[137726107] 'agreement among raft nodes before linearized reading' (duration: 312.184454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:23.154441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.841362Z","time spent":"312.946825ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T17:49:23.154312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:22.709094Z","time spent":"445.199283ms","remote":"127.0.0.1:60726","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-04-11T17:49:24.576491Z","caller":"traceutil/trace.go:171","msg":"trace[1186967030] linearizableReadLoop","detail":"{readStateIndex:619; appliedIndex:618; }","duration":"229.126656ms","start":"2026-04-11T17:49:24.347338Z","end":"2026-04-11T17:49:24.576465Z","steps":["trace[1186967030] 'read index received' (duration: 209.646693ms)","trace[1186967030] 'applied index is now lower than readState.Index' (duration: 19.479013ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:24.576767Z","caller":"traceutil/trace.go:171","msg":"trace[1594590595] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"230.061911ms","start":"2026-04-11T17:49:24.346647Z","end":"2026-04-11T17:49:24.576709Z","steps":["trace[1594590595] 'process raft request' (duration: 210.298101ms)","trace[1594590595] 'compare' (duration: 19.299268ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:24.57723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.844555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io\" ","response":"range_response_count:1 size:570105"} {"level":"info","ts":"2026-04-11T17:49:24.57727Z","caller":"traceutil/trace.go:171","msg":"trace[50291631] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/envoyproxies.gateway.envoyproxy.io; range_end:; response_count:1; response_revision:592; }","duration":"229.937859ms","start":"2026-04-11T17:49:24.347322Z","end":"2026-04-11T17:49:24.57726Z","steps":["trace[50291631] 'agreement among raft nodes before linearized reading' (duration: 229.25962ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:27.447418Z","caller":"traceutil/trace.go:171","msg":"trace[1084890768] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"175.828737ms","start":"2026-04-11T17:49:27.271546Z","end":"2026-04-11T17:49:27.447375Z","steps":["trace[1084890768] 'process raft request' (duration: 166.21145ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:29.1126Z","caller":"traceutil/trace.go:171","msg":"trace[598700202] transaction","detail":"{read_only:false; response_revision:614; number_of_response:1; }","duration":"132.811263ms","start":"2026-04-11T17:49:28.97976Z","end":"2026-04-11T17:49:29.112571Z","steps":["trace[598700202] 'process raft request' (duration: 125.393024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:29.112987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.640296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:29.11309Z","caller":"traceutil/trace.go:171","msg":"trace[1537410795] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"117.79606ms","start":"2026-04-11T17:49:28.995271Z","end":"2026-04-11T17:49:29.113067Z","steps":["trace[1537410795] 'agreement among raft nodes before linearized reading' (duration: 117.604895ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:29.113149Z","caller":"traceutil/trace.go:171","msg":"trace[203852236] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:641; }","duration":"117.184863ms","start":"2026-04-11T17:49:28.995308Z","end":"2026-04-11T17:49:29.112493Z","steps":["trace[203852236] 'read index received' (duration: 108.717157ms)","trace[203852236] 'applied index is now lower than readState.Index' (duration: 8.466716ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:29.113344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.955184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:29.113384Z","caller":"traceutil/trace.go:171","msg":"trace[1468166579] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"118.048328ms","start":"2026-04-11T17:49:28.99532Z","end":"2026-04-11T17:49:29.113369Z","steps":["trace[1468166579] 'agreement among raft nodes before linearized reading' (duration: 117.952305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:29.113653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.619415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:29.113684Z","caller":"traceutil/trace.go:171","msg":"trace[368845336] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:614; }","duration":"117.691426ms","start":"2026-04-11T17:49:28.995984Z","end":"2026-04-11T17:49:29.113675Z","steps":["trace[368845336] 'agreement among raft nodes before linearized reading' (duration: 117.646425ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:29.409705Z","caller":"traceutil/trace.go:171","msg":"trace[2077690737] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"139.808671ms","start":"2026-04-11T17:49:29.269875Z","end":"2026-04-11T17:49:29.409683Z","steps":["trace[2077690737] 'process raft request' (duration: 116.478114ms)","trace[2077690737] 'compare' (duration: 23.195913ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:30.356708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.406259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:30.356764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.089929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:30.356803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.295954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.356823Z","caller":"traceutil/trace.go:171","msg":"trace[157975029] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"287.562803ms","start":"2026-04-11T17:49:30.069225Z","end":"2026-04-11T17:49:30.356788Z","steps":["trace[157975029] 'range keys from in-memory index tree' (duration: 287.285007ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.356842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.834698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.356876Z","caller":"traceutil/trace.go:171","msg":"trace[1433882984] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:625; }","duration":"335.400806ms","start":"2026-04-11T17:49:30.021454Z","end":"2026-04-11T17:49:30.356855Z","steps":["trace[1433882984] 'range keys from in-memory index tree' (duration: 335.1402ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:30.356876Z","caller":"traceutil/trace.go:171","msg":"trace[1189779962] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:625; }","duration":"275.200922ms","start":"2026-04-11T17:49:30.081641Z","end":"2026-04-11T17:49:30.356842Z","steps":["trace[1189779962] 'range keys from in-memory index tree' (duration: 275.048398ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:30.3569Z","caller":"traceutil/trace.go:171","msg":"trace[665884634] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"307.93167ms","start":"2026-04-11T17:49:30.048952Z","end":"2026-04-11T17:49:30.356883Z","steps":["trace[665884634] 'count revisions from in-memory index tree' (duration: 307.711794ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.356878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.253453ms","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":"warn","ts":"2026-04-11T17:49:30.356928Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.021434Z","time spent":"335.48237ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 "} {"level":"warn","ts":"2026-04-11T17:49:30.356939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.048933Z","time spent":"307.997922ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:49:30.356909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.860178ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.356969Z","caller":"traceutil/trace.go:171","msg":"trace[2008520525] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:625; }","duration":"335.385906ms","start":"2026-04-11T17:49:30.021564Z","end":"2026-04-11T17:49:30.35695Z","steps":["trace[2008520525] 'count revisions from in-memory index tree' (duration: 335.14093ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.357045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.021558Z","time spent":"335.474099ms","remote":"127.0.0.1:60238","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-04-11T17:49:30.357048Z","caller":"traceutil/trace.go:171","msg":"trace[381426028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"308.049094ms","start":"2026-04-11T17:49:30.04897Z","end":"2026-04-11T17:49:30.357019Z","steps":["trace[381426028] 'range keys from in-memory index tree' (duration: 307.742416ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.357107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.048952Z","time spent":"308.139785ms","remote":"127.0.0.1:60248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":109,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 "} {"level":"warn","ts":"2026-04-11T17:49:30.356631Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.195793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:30.357111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.920242ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.357279Z","caller":"traceutil/trace.go:171","msg":"trace[462417369] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"288.118039ms","start":"2026-04-11T17:49:30.069151Z","end":"2026-04-11T17:49:30.357269Z","steps":["trace[462417369] 'range keys from in-memory index tree' (duration: 287.873241ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.357276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.474639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.35731Z","caller":"traceutil/trace.go:171","msg":"trace[1026566043] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:625; }","duration":"275.51658ms","start":"2026-04-11T17:49:30.081784Z","end":"2026-04-11T17:49:30.3573Z","steps":["trace[1026566043] 'count revisions from in-memory index tree' (duration: 275.452369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.356714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.691869ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.357348Z","caller":"traceutil/trace.go:171","msg":"trace[13853325] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:625; }","duration":"335.403608ms","start":"2026-04-11T17:49:30.021936Z","end":"2026-04-11T17:49:30.35734Z","steps":["trace[13853325] 'range keys from in-memory index tree' (duration: 334.589286ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.35738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.021927Z","time spent":"335.442468ms","remote":"127.0.0.1:60238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":97,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 "} {"level":"info","ts":"2026-04-11T17:49:30.357254Z","caller":"traceutil/trace.go:171","msg":"trace[159769906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:625; }","duration":"306.852881ms","start":"2026-04-11T17:49:30.050374Z","end":"2026-04-11T17:49:30.357226Z","steps":["trace[159769906] 'range keys from in-memory index tree' (duration: 306.114271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.357524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.050367Z","time spent":"307.141779ms","remote":"127.0.0.1:60480","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-04-11T17:49:30.35715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.859821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.357676Z","caller":"traceutil/trace.go:171","msg":"trace[59692475] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:625; }","duration":"288.395016ms","start":"2026-04-11T17:49:30.069267Z","end":"2026-04-11T17:49:30.357662Z","steps":["trace[59692475] 'count revisions from in-memory index tree' (duration: 287.79087ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.356618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.886407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:30.358128Z","caller":"traceutil/trace.go:171","msg":"trace[1616076983] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:625; }","duration":"275.445719ms","start":"2026-04-11T17:49:30.082656Z","end":"2026-04-11T17:49:30.358102Z","steps":["trace[1616076983] 'range keys from in-memory index tree' (duration: 273.842265ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:30.858989Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394288988,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:49:31.359532Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394288988,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:49:31.540306Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.275013282s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:49:31.542037Z","caller":"traceutil/trace.go:171","msg":"trace[1301831291] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"1.183156938s","start":"2026-04-11T17:49:30.358851Z","end":"2026-04-11T17:49:31.542008Z","steps":["trace[1301831291] 'read index received' (duration: 1.18212303s)","trace[1301831291] 'applied index is now lower than readState.Index' (duration: 1.032818ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:31.542302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.183420794s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:31.542382Z","caller":"traceutil/trace.go:171","msg":"trace[1781672551] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"1.183513998s","start":"2026-04-11T17:49:30.358846Z","end":"2026-04-11T17:49:31.54236Z","steps":["trace[1781672551] 'agreement among raft nodes before linearized reading' (duration: 1.183363494s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:31.542429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.358794Z","time spent":"1.183626311s","remote":"127.0.0.1:60476","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-04-11T17:49:31.542713Z","caller":"traceutil/trace.go:171","msg":"trace[674069740] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.279088131s","start":"2026-04-11T17:49:30.263613Z","end":"2026-04-11T17:49:31.542701Z","steps":["trace[674069740] 'process raft request' (duration: 1.277023276s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:31.542808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:30.263581Z","time spent":"1.279158724s","remote":"127.0.0.1:60572","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:31.542799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.706589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:49:31.54287Z","caller":"traceutil/trace.go:171","msg":"trace[1350851933] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:626; }","duration":"274.831083ms","start":"2026-04-11T17:49:31.268021Z","end":"2026-04-11T17:49:31.542852Z","steps":["trace[1350851933] 'agreement among raft nodes before linearized reading' (duration: 274.620377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:31.542946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.459311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:49:31.542996Z","caller":"traceutil/trace.go:171","msg":"trace[1965224327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:626; }","duration":"271.530365ms","start":"2026-04-11T17:49:31.27145Z","end":"2026-04-11T17:49:31.54298Z","steps":["trace[1965224327] 'agreement among raft nodes before linearized reading' (duration: 271.429512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:31.543103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.871006ms","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-04-11T17:49:31.543118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.402234ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-11T17:49:31.543138Z","caller":"traceutil/trace.go:171","msg":"trace[79569847] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:626; }","duration":"270.942448ms","start":"2026-04-11T17:49:31.272185Z","end":"2026-04-11T17:49:31.543128Z","steps":["trace[79569847] 'agreement among raft nodes before linearized reading' (duration: 270.815185ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:31.543203Z","caller":"traceutil/trace.go:171","msg":"trace[1344862298] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:626; }","duration":"273.545727ms","start":"2026-04-11T17:49:31.269637Z","end":"2026-04-11T17:49:31.543183Z","steps":["trace[1344862298] 'agreement among raft nodes before linearized reading' (duration: 273.27848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:32.231461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"663.1972ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:32.231679Z","caller":"traceutil/trace.go:171","msg":"trace[480264672] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"623.345272ms","start":"2026-04-11T17:49:31.608321Z","end":"2026-04-11T17:49:32.231666Z","steps":["trace[480264672] 'process raft request' (duration: 623.32023ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:32.23172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.608303Z","time spent":"623.400453ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:49:32.23185Z","caller":"traceutil/trace.go:171","msg":"trace[592094356] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"683.686629ms","start":"2026-04-11T17:49:31.548156Z","end":"2026-04-11T17:49:32.231842Z","steps":["trace[592094356] 'process raft request' (duration: 19.933344ms)","trace[592094356] 'compare' (duration: 663.089227ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:32.231885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.548135Z","time spent":"683.729439ms","remote":"127.0.0.1:60726","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-04-11T17:49:32.232042Z","caller":"traceutil/trace.go:171","msg":"trace[1205890701] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:654; }","duration":"676.474495ms","start":"2026-04-11T17:49:31.555555Z","end":"2026-04-11T17:49:32.23203Z","steps":["trace[1205890701] 'read index received' (duration: 12.475143ms)","trace[1205890701] 'applied index is now lower than readState.Index' (duration: 663.997572ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:32.232097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"676.537306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:32.232118Z","caller":"traceutil/trace.go:171","msg":"trace[844163925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"676.578ms","start":"2026-04-11T17:49:31.555532Z","end":"2026-04-11T17:49:32.23211Z","steps":["trace[844163925] 'agreement among raft nodes before linearized reading' (duration: 676.538718ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:32.232138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.555516Z","time spent":"676.61785ms","remote":"127.0.0.1:60480","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-04-11T17:49:32.232228Z","caller":"traceutil/trace.go:171","msg":"trace[1964986832] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"683.588468ms","start":"2026-04-11T17:49:31.548633Z","end":"2026-04-11T17:49:32.232222Z","steps":["trace[1964986832] 'process raft request' (duration: 682.959251ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:32.232241Z","caller":"traceutil/trace.go:171","msg":"trace[1044734763] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"682.560459ms","start":"2026-04-11T17:49:31.549672Z","end":"2026-04-11T17:49:32.232232Z","steps":["trace[1044734763] 'process raft request' (duration: 681.941702ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:32.232281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.549663Z","time spent":"682.59296ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:32.232277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.548628Z","time spent":"683.617488ms","remote":"127.0.0.1:60726","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-04-11T17:49:32.232331Z","caller":"traceutil/trace.go:171","msg":"trace[479624004] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"683.74209ms","start":"2026-04-11T17:49:31.548583Z","end":"2026-04-11T17:49:32.232325Z","steps":["trace[479624004] 'process raft request' (duration: 682.97225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:32.232368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:31.548574Z","time spent":"683.773862ms","remote":"127.0.0.1:60726","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-04-11T17:49:33.548711Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394289001,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:49:33.631546Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.287396009s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-11T17:49:33.719519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38246481s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:49:33.719785Z","caller":"traceutil/trace.go:171","msg":"trace[1071130960] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.399342473s","start":"2026-04-11T17:49:32.320393Z","end":"2026-04-11T17:49:33.719736Z","steps":["trace[1071130960] 'process raft request' (duration: 15.866577ms)","trace[1071130960] 'compare' (duration: 1.382125541s)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:33.719928Z","caller":"traceutil/trace.go:171","msg":"trace[121160512] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"1.399324931s","start":"2026-04-11T17:49:32.320544Z","end":"2026-04-11T17:49:33.719869Z","steps":["trace[121160512] 'process raft request' (duration: 1.399115756s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:33.719978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:32.320368Z","time spent":"1.399502646s","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:49:33.720063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:32.320517Z","time spent":"1.399510187s","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:49:33.720606Z","caller":"traceutil/trace.go:171","msg":"trace[1990767031] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"1.398248133s","start":"2026-04-11T17:49:32.322338Z","end":"2026-04-11T17:49:33.720586Z","steps":["trace[1990767031] 'process raft request' (duration: 1.398176051s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:33.7207Z","caller":"traceutil/trace.go:171","msg":"trace[1384937875] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"1.398612723s","start":"2026-04-11T17:49:32.322072Z","end":"2026-04-11T17:49:33.720685Z","steps":["trace[1384937875] 'process raft request' (duration: 1.398284964s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:33.720752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:32.322328Z","time spent":"1.398351796s","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:33.720785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:32.322058Z","time spent":"1.398674324s","remote":"127.0.0.1:60644","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:33.899404Z","caller":"traceutil/trace.go:171","msg":"trace[293385165] linearizableReadLoop","detail":"{readStateIndex:665; appliedIndex:660; }","duration":"851.316382ms","start":"2026-04-11T17:49:33.048042Z","end":"2026-04-11T17:49:33.899359Z","steps":["trace[293385165] 'read index received' (duration: 583.775206ms)","trace[293385165] 'applied index is now lower than readState.Index' (duration: 267.539626ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:33.899589Z","caller":"traceutil/trace.go:171","msg":"trace[1766702121] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"1.438187004s","start":"2026-04-11T17:49:32.461388Z","end":"2026-04-11T17:49:33.899575Z","steps":["trace[1766702121] 'process raft request' (duration: 1.369584182s)","trace[1766702121] 'compare' (duration: 68.187991ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:33.899828Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:32.461359Z","time spent":"1.438353259s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:33.899836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"725.400734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T17:49:33.899951Z","caller":"traceutil/trace.go:171","msg":"trace[1432612597] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:637; }","duration":"725.526108ms","start":"2026-04-11T17:49:33.174368Z","end":"2026-04-11T17:49:33.899894Z","steps":["trace[1432612597] 'agreement among raft nodes before linearized reading' (duration: 725.299162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:33.900017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:33.174346Z","time spent":"725.658931ms","remote":"127.0.0.1:60492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-04-11T17:49:33.899999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.941699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:33.900089Z","caller":"traceutil/trace.go:171","msg":"trace[57534931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"852.092893ms","start":"2026-04-11T17:49:33.047972Z","end":"2026-04-11T17:49:33.900065Z","steps":["trace[57534931] 'agreement among raft nodes before linearized reading' (duration: 851.909879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:33.900141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:33.045999Z","time spent":"854.133707ms","remote":"127.0.0.1:60480","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-04-11T17:49:34.452311Z","caller":"traceutil/trace.go:171","msg":"trace[794813185] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"111.286183ms","start":"2026-04-11T17:49:34.341003Z","end":"2026-04-11T17:49:34.452289Z","steps":["trace[794813185] 'process raft request' (duration: 107.755008ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:34.452673Z","caller":"traceutil/trace.go:171","msg":"trace[312211701] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"111.20592ms","start":"2026-04-11T17:49:34.341436Z","end":"2026-04-11T17:49:34.452642Z","steps":["trace[312211701] 'process raft request' (duration: 111.067776ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:34.452792Z","caller":"traceutil/trace.go:171","msg":"trace[1070147226] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"111.610251ms","start":"2026-04-11T17:49:34.341084Z","end":"2026-04-11T17:49:34.452695Z","steps":["trace[1070147226] 'process raft request' (duration: 111.167369ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:35.080615Z","caller":"traceutil/trace.go:171","msg":"trace[1460502282] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"118.435624ms","start":"2026-04-11T17:49:34.962151Z","end":"2026-04-11T17:49:35.080587Z","steps":["trace[1460502282] 'process raft request' (duration: 118.177278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:36.589582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.556025ms","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-04-11T17:49:36.589615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.851116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-11T17:49:36.589673Z","caller":"traceutil/trace.go:171","msg":"trace[480946168] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:651; }","duration":"189.721758ms","start":"2026-04-11T17:49:36.399931Z","end":"2026-04-11T17:49:36.589653Z","steps":["trace[480946168] 'range keys from in-memory index tree' (duration: 189.268206ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:36.589756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.829429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-11T17:49:36.589797Z","caller":"traceutil/trace.go:171","msg":"trace[1792845320] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:651; }","duration":"132.904831ms","start":"2026-04-11T17:49:36.456883Z","end":"2026-04-11T17:49:36.589788Z","steps":["trace[1792845320] 'range keys from in-memory index tree' (duration: 132.747886ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:36.589817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.50449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:49:36.589893Z","caller":"traceutil/trace.go:171","msg":"trace[388191599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:651; }","duration":"132.583323ms","start":"2026-04-11T17:49:36.457277Z","end":"2026-04-11T17:49:36.589861Z","steps":["trace[388191599] 'range keys from in-memory index tree' (duration: 132.396877ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:36.589687Z","caller":"traceutil/trace.go:171","msg":"trace[746237578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:651; }","duration":"133.959959ms","start":"2026-04-11T17:49:36.455709Z","end":"2026-04-11T17:49:36.589669Z","steps":["trace[746237578] 'range keys from in-memory index tree' (duration: 133.679081ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:38.094414Z","caller":"traceutil/trace.go:171","msg":"trace[1891927152] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"271.746548ms","start":"2026-04-11T17:49:37.822637Z","end":"2026-04-11T17:49:38.094384Z","steps":["trace[1891927152] 'process raft request' (duration: 271.499199ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:38.211472Z","caller":"traceutil/trace.go:171","msg":"trace[1119279192] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"106.254901ms","start":"2026-04-11T17:49:38.105197Z","end":"2026-04-11T17:49:38.211452Z","steps":["trace[1119279192] 'process raft request' (duration: 106.129108ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:50.444137Z","caller":"traceutil/trace.go:171","msg":"trace[1144452021] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"132.098588ms","start":"2026-04-11T17:49:50.311999Z","end":"2026-04-11T17:49:50.444098Z","steps":["trace[1144452021] 'process raft request' (duration: 106.717728ms)","trace[1144452021] 'compare' (duration: 25.133612ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:51.936025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.003795063s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:49:51.936143Z","caller":"traceutil/trace.go:171","msg":"trace[2136602091] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.003998458s","start":"2026-04-11T17:49:50.932117Z","end":"2026-04-11T17:49:51.936115Z","steps":["trace[2136602091] 'range keys from in-memory index tree' (duration: 1.003424962s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.936203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:50.932082Z","time spent":"1.004105552s","remote":"127.0.0.1:60726","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-04-11T17:49:51.936279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189267788s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:49:51.936362Z","caller":"traceutil/trace.go:171","msg":"trace[687150616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.189403101s","start":"2026-04-11T17:49:50.746942Z","end":"2026-04-11T17:49:51.936345Z","steps":["trace[687150616] 'range keys from in-memory index tree' (duration: 1.188840717s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.93642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:50.746913Z","time spent":"1.189486544s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:49:51.936597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.189400032s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:49:51.936641Z","caller":"traceutil/trace.go:171","msg":"trace[1581068502] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:794; }","duration":"1.189508385s","start":"2026-04-11T17:49:50.747119Z","end":"2026-04-11T17:49:51.936627Z","steps":["trace[1581068502] 'range keys from in-memory index tree' (duration: 1.189212238s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.936683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:50.747019Z","time spent":"1.189651839s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:49:51.936667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.955325ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:51.936812Z","caller":"traceutil/trace.go:171","msg":"trace[2096981294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:794; }","duration":"890.172361ms","start":"2026-04-11T17:49:51.046613Z","end":"2026-04-11T17:49:51.936785Z","steps":["trace[2096981294] 'range keys from in-memory index tree' (duration: 889.834531ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.936876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.046581Z","time spent":"890.281444ms","remote":"127.0.0.1:60480","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-04-11T17:49:51.936981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.18933213s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:49:51.937045Z","caller":"traceutil/trace.go:171","msg":"trace[744040279] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:794; }","duration":"1.189394501s","start":"2026-04-11T17:49:50.747634Z","end":"2026-04-11T17:49:51.937028Z","steps":["trace[744040279] 'range keys from in-memory index tree' (duration: 1.189198796s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.937099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:50.747602Z","time spent":"1.189485454s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T17:49:51.936025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.239071782s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:49:51.937203Z","caller":"traceutil/trace.go:171","msg":"trace[292374475] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:794; }","duration":"1.240332145s","start":"2026-04-11T17:49:50.696851Z","end":"2026-04-11T17:49:51.937183Z","steps":["trace[292374475] 'range keys from in-memory index tree' (duration: 1.238787043s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:51.937174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.242520763s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:49:51.937296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:50.69682Z","time spent":"1.240432117s","remote":"127.0.0.1:60726","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-04-11T17:49:51.937334Z","caller":"traceutil/trace.go:171","msg":"trace[1138747374] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:794; }","duration":"1.242690148s","start":"2026-04-11T17:49:50.694619Z","end":"2026-04-11T17:49:51.937309Z","steps":["trace[1138747374] 'range keys from in-memory index tree' (duration: 1.242493003s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.042462Z","caller":"traceutil/trace.go:171","msg":"trace[832597667] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"102.575945ms","start":"2026-04-11T17:49:51.939849Z","end":"2026-04-11T17:49:52.042425Z","steps":["trace[832597667] 'read index received' (duration: 102.267187ms)","trace[832597667] 'applied index is now lower than readState.Index' (duration: 307.198µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:49:52.042569Z","caller":"traceutil/trace.go:171","msg":"trace[1881395571] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"767.794445ms","start":"2026-04-11T17:49:51.274738Z","end":"2026-04-11T17:49:52.042533Z","steps":["trace[1881395571] 'process raft request' (duration: 767.262001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.042709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.816642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:52.042778Z","caller":"traceutil/trace.go:171","msg":"trace[774571039] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"102.935166ms","start":"2026-04-11T17:49:51.939822Z","end":"2026-04-11T17:49:52.042757Z","steps":["trace[774571039] 'agreement among raft nodes before linearized reading' (duration: 102.751191ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.042804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.274704Z","time spent":"767.948789ms","remote":"127.0.0.1:60698","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:49:52.4701Z","caller":"traceutil/trace.go:171","msg":"trace[1718223902] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"527.04525ms","start":"2026-04-11T17:49:51.943019Z","end":"2026-04-11T17:49:52.470065Z","steps":["trace[1718223902] 'process raft request' (duration: 526.926237ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.470179Z","caller":"traceutil/trace.go:171","msg":"trace[1182299879] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"527.206614ms","start":"2026-04-11T17:49:51.942844Z","end":"2026-04-11T17:49:52.470051Z","steps":["trace[1182299879] 'process raft request' (duration: 526.896516ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.470631Z","caller":"traceutil/trace.go:171","msg":"trace[1018864952] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"527.05177ms","start":"2026-04-11T17:49:51.943541Z","end":"2026-04-11T17:49:52.470593Z","steps":["trace[1018864952] 'process raft request' (duration: 526.476854ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:49:52.470679Z","caller":"traceutil/trace.go:171","msg":"trace[1235282201] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:828; }","duration":"418.605346ms","start":"2026-04-11T17:49:52.052047Z","end":"2026-04-11T17:49:52.470652Z","steps":["trace[1235282201] 'read index received' (duration: 417.541117ms)","trace[1235282201] 'applied index is now lower than readState.Index' (duration: 1.062899ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:49:52.470832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.94353Z","time spent":"527.190723ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:49:52.470792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.942821Z","time spent":"527.681296ms","remote":"127.0.0.1:60726","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-04-11T17:49:52.470415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.943009Z","time spent":"527.200803ms","remote":"127.0.0.1:60726","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-04-11T17:49:52.471108Z","caller":"traceutil/trace.go:171","msg":"trace[438733015] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"527.376129ms","start":"2026-04-11T17:49:51.943719Z","end":"2026-04-11T17:49:52.471095Z","steps":["trace[438733015] 'process raft request' (duration: 526.878736ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.471164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.943713Z","time spent":"527.42025ms","remote":"127.0.0.1:60726","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-04-11T17:49:52.471151Z","caller":"traceutil/trace.go:171","msg":"trace[471482977] transaction","detail":"{read_only:false; response_revision:799; number_of_response:1; }","duration":"527.533063ms","start":"2026-04-11T17:49:51.943595Z","end":"2026-04-11T17:49:52.471128Z","steps":["trace[471482977] 'process raft request' (duration: 526.905006ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.471257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:51.94359Z","time spent":"527.617595ms","remote":"127.0.0.1:60726","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-04-11T17:49:52.471263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.198293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:49:52.471353Z","caller":"traceutil/trace.go:171","msg":"trace[1765375599] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:800; }","duration":"419.323695ms","start":"2026-04-11T17:49:52.051981Z","end":"2026-04-11T17:49:52.471305Z","steps":["trace[1765375599] 'agreement among raft nodes before linearized reading' (duration: 419.187602ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.471414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"337.954117ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T17:49:52.471437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.051957Z","time spent":"419.466009ms","remote":"127.0.0.1:60480","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-04-11T17:49:52.47147Z","caller":"traceutil/trace.go:171","msg":"trace[407640939] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:800; }","duration":"338.082872ms","start":"2026-04-11T17:49:52.133371Z","end":"2026-04-11T17:49:52.471454Z","steps":["trace[407640939] 'agreement among raft nodes before linearized reading' (duration: 337.965549ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:49:52.471519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:49:52.133342Z","time spent":"338.167113ms","remote":"127.0.0.1:60968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:49:52.606143Z","caller":"traceutil/trace.go:171","msg":"trace[1718390504] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"120.088105ms","start":"2026-04-11T17:49:52.486026Z","end":"2026-04-11T17:49:52.606114Z","steps":["trace[1718390504] 'process raft request' (duration: 117.254209ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:10.775577Z","caller":"traceutil/trace.go:171","msg":"trace[875746710] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"147.765176ms","start":"2026-04-11T17:50:10.62778Z","end":"2026-04-11T17:50:10.775545Z","steps":["trace[875746710] 'process raft request' (duration: 106.8301ms)","trace[875746710] 'compare' (duration: 40.397101ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:10.776033Z","caller":"traceutil/trace.go:171","msg":"trace[612790123] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"148.013803ms","start":"2026-04-11T17:50:10.627986Z","end":"2026-04-11T17:50:10.776Z","steps":["trace[612790123] 'process raft request' (duration: 147.216101ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:29.072273Z","caller":"traceutil/trace.go:171","msg":"trace[237167329] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"193.861871ms","start":"2026-04-11T17:50:28.878387Z","end":"2026-04-11T17:50:29.072249Z","steps":["trace[237167329] 'read index received' (duration: 193.718797ms)","trace[237167329] 'applied index is now lower than readState.Index' (duration: 141.874µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:29.072642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.224661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:50:29.072706Z","caller":"traceutil/trace.go:171","msg":"trace[1260361128] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1015; }","duration":"194.307293ms","start":"2026-04-11T17:50:28.878379Z","end":"2026-04-11T17:50:29.072687Z","steps":["trace[1260361128] 'agreement among raft nodes before linearized reading' (duration: 194.106167ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:29.072725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.957982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:50:29.072709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.194099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:50:29.072796Z","caller":"traceutil/trace.go:171","msg":"trace[79121151] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1015; }","duration":"126.053264ms","start":"2026-04-11T17:50:28.946726Z","end":"2026-04-11T17:50:29.072779Z","steps":["trace[79121151] 'agreement among raft nodes before linearized reading' (duration: 125.87852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:29.072795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.789442ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:50:29.072859Z","caller":"traceutil/trace.go:171","msg":"trace[1801101225] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1015; }","duration":"134.011588ms","start":"2026-04-11T17:50:28.938826Z","end":"2026-04-11T17:50:29.072838Z","steps":["trace[1801101225] 'agreement among raft nodes before linearized reading' (duration: 133.748051ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:29.072834Z","caller":"traceutil/trace.go:171","msg":"trace[626417248] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1015; }","duration":"129.345542ms","start":"2026-04-11T17:50:28.943475Z","end":"2026-04-11T17:50:29.072821Z","steps":["trace[626417248] 'agreement among raft nodes before linearized reading' (duration: 129.124286ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:29.073033Z","caller":"traceutil/trace.go:171","msg":"trace[1995124434] transaction","detail":"{read_only:false; response_revision:1015; number_of_response:1; }","duration":"195.831122ms","start":"2026-04-11T17:50:28.877156Z","end":"2026-04-11T17:50:29.072987Z","steps":["trace[1995124434] 'process raft request' (duration: 194.931519ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:29.07309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.285418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:50:29.073231Z","caller":"traceutil/trace.go:171","msg":"trace[1385031129] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1015; }","duration":"130.511983ms","start":"2026-04-11T17:50:28.942692Z","end":"2026-04-11T17:50:29.073204Z","steps":["trace[1385031129] 'agreement among raft nodes before linearized reading' (duration: 130.137533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:29.826414Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394289801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:50:29.834993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.863253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:50:29.835109Z","caller":"traceutil/trace.go:171","msg":"trace[1024819050] transaction","detail":"{read_only:false; response_revision:1016; number_of_response:1; }","duration":"756.78912ms","start":"2026-04-11T17:50:29.078299Z","end":"2026-04-11T17:50:29.835088Z","steps":["trace[1024819050] 'process raft request' (duration: 83.714611ms)","trace[1024819050] 'compare' (duration: 672.523854ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:29.835173Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.07828Z","time spent":"756.863141ms","remote":"127.0.0.1:60726","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-04-11T17:50:30.32722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394289801,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:50:30.418955Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.256927049s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-11T17:50:31.269556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.534668ms","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-04-11T17:50:31.269684Z","caller":"traceutil/trace.go:171","msg":"trace[405124647] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1016; }","duration":"575.682602ms","start":"2026-04-11T17:50:30.693981Z","end":"2026-04-11T17:50:31.269664Z","steps":["trace[405124647] 'range keys from in-memory index tree' (duration: 575.516377ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:31.269855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"850.593841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:50:31.270069Z","caller":"traceutil/trace.go:171","msg":"trace[1763218602] linearizableReadLoop","detail":"{readStateIndex:1063; appliedIndex:1059; }","duration":"1.944493675s","start":"2026-04-11T17:50:29.325564Z","end":"2026-04-11T17:50:31.270058Z","steps":["trace[1763218602] 'read index received' (duration: 1.093529865s)","trace[1763218602] 'applied index is now lower than readState.Index' (duration: 850.96272ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:31.270284Z","caller":"traceutil/trace.go:171","msg":"trace[203881817] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"2.191839908s","start":"2026-04-11T17:50:29.078434Z","end":"2026-04-11T17:50:31.270273Z","steps":["trace[203881817] 'process raft request' (duration: 1.340721353s)","trace[203881817] 'compare' (duration: 850.487808ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:31.270342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.07841Z","time spent":"2.19190459s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:50:31.270469Z","caller":"traceutil/trace.go:171","msg":"trace[592099317] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"2.191964391s","start":"2026-04-11T17:50:29.078499Z","end":"2026-04-11T17:50:31.270463Z","steps":["trace[592099317] 'process raft request' (duration: 2.191417577s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:31.27051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.078482Z","time spent":"2.192007743s","remote":"127.0.0.1:60726","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-04-11T17:50:31.270604Z","caller":"traceutil/trace.go:171","msg":"trace[1879446308] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"2.192098655s","start":"2026-04-11T17:50:29.078498Z","end":"2026-04-11T17:50:31.270597Z","steps":["trace[1879446308] 'process raft request' (duration: 2.191479069s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:31.270645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.078475Z","time spent":"2.192150527s","remote":"127.0.0.1:60726","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-04-11T17:50:31.270709Z","caller":"traceutil/trace.go:171","msg":"trace[405612565] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"2.191549119s","start":"2026-04-11T17:50:29.079155Z","end":"2026-04-11T17:50:31.270704Z","steps":["trace[405612565] 'process raft request' (duration: 2.190851621s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:31.270744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.079147Z","time spent":"2.191580961s","remote":"127.0.0.1:60726","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-04-11T17:50:31.270789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.945201565s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:50:31.270879Z","caller":"traceutil/trace.go:171","msg":"trace[2091850959] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1020; }","duration":"1.945523333s","start":"2026-04-11T17:50:29.325336Z","end":"2026-04-11T17:50:31.270859Z","steps":["trace[2091850959] 'agreement among raft nodes before linearized reading' (duration: 1.945359548s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:31.270931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.325299Z","time spent":"1.945620876s","remote":"127.0.0.1:60924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:50:31.770733Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394289808,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T17:50:32.032061Z","caller":"traceutil/trace.go:171","msg":"trace[1450832691] linearizableReadLoop","detail":"{readStateIndex:1064; appliedIndex:1063; }","duration":"761.935088ms","start":"2026-04-11T17:50:31.270098Z","end":"2026-04-11T17:50:32.032033Z","steps":["trace[1450832691] 'read index received' (duration: 741.078289ms)","trace[1450832691] 'applied index is now lower than readState.Index' (duration: 20.855409ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:32.032302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.986441449s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:50:32.032331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"903.969881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:50:32.032397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.376282055s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T17:50:32.032359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.471731341s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:50:32.03242Z","caller":"traceutil/trace.go:171","msg":"trace[1177460438] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:1021; }","duration":"1.376336626s","start":"2026-04-11T17:50:30.656075Z","end":"2026-04-11T17:50:32.032412Z","steps":["trace[1177460438] 'agreement among raft nodes before linearized reading' (duration: 1.376298426s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:32.032407Z","caller":"traceutil/trace.go:171","msg":"trace[889369080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1021; }","duration":"1.986594134s","start":"2026-04-11T17:50:30.045782Z","end":"2026-04-11T17:50:32.032377Z","steps":["trace[889369080] 'agreement among raft nodes before linearized reading' (duration: 1.986381918s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:32.032428Z","caller":"traceutil/trace.go:171","msg":"trace[1608651666] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:1021; }","duration":"904.144564ms","start":"2026-04-11T17:50:31.128259Z","end":"2026-04-11T17:50:32.032403Z","steps":["trace[1608651666] 'agreement among raft nodes before linearized reading' (duration: 903.896838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:32.032443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:30.656054Z","time spent":"1.376383747s","remote":"127.0.0.1:60984","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:50:32.032447Z","caller":"traceutil/trace.go:171","msg":"trace[2095886970] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:1021; }","duration":"2.471874524s","start":"2026-04-11T17:50:29.56055Z","end":"2026-04-11T17:50:32.032424Z","steps":["trace[2095886970] 'agreement among raft nodes before linearized reading' (duration: 2.471637498s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:32.032465Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:30.045752Z","time spent":"1.986700926s","remote":"127.0.0.1:60480","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-04-11T17:50:32.032503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:29.560524Z","time spent":"2.471970458s","remote":"127.0.0.1:60966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":2,"response size":30,"request content":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:50:32.032348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.267687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:50:32.032647Z","caller":"traceutil/trace.go:171","msg":"trace[1179888541] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"192.612486ms","start":"2026-04-11T17:50:31.840025Z","end":"2026-04-11T17:50:32.032638Z","steps":["trace[1179888541] 'agreement among raft nodes before linearized reading' (duration: 192.180055ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:32.032646Z","caller":"traceutil/trace.go:171","msg":"trace[545932055] transaction","detail":"{read_only:false; response_revision:1021; number_of_response:1; }","duration":"952.659034ms","start":"2026-04-11T17:50:31.079972Z","end":"2026-04-11T17:50:32.032631Z","steps":["trace[545932055] 'process raft request' (duration: 931.318613ms)","trace[545932055] 'compare' (duration: 20.231552ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:32.032471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:31.128227Z","time spent":"904.235658ms","remote":"127.0.0.1:60112","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:50:32.032754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:31.079944Z","time spent":"952.733026ms","remote":"127.0.0.1:60726","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-04-11T17:50:33.419596Z","caller":"traceutil/trace.go:171","msg":"trace[1192539283] transaction","detail":"{read_only:false; response_revision:1024; number_of_response:1; }","duration":"139.194647ms","start":"2026-04-11T17:50:33.280379Z","end":"2026-04-11T17:50:33.419574Z","steps":["trace[1192539283] 'process raft request' (duration: 139.031212ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:33.487217Z","caller":"traceutil/trace.go:171","msg":"trace[939353680] transaction","detail":"{read_only:false; response_revision:1025; number_of_response:1; }","duration":"206.078077ms","start":"2026-04-11T17:50:33.281103Z","end":"2026-04-11T17:50:33.487181Z","steps":["trace[939353680] 'process raft request' (duration: 205.79826ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:33.487225Z","caller":"traceutil/trace.go:171","msg":"trace[1121992607] transaction","detail":"{read_only:false; response_revision:1026; number_of_response:1; }","duration":"205.947394ms","start":"2026-04-11T17:50:33.281223Z","end":"2026-04-11T17:50:33.487171Z","steps":["trace[1121992607] 'process raft request' (duration: 205.846321ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:33.487426Z","caller":"traceutil/trace.go:171","msg":"trace[1325273239] transaction","detail":"{read_only:false; response_revision:1027; number_of_response:1; }","duration":"206.139639ms","start":"2026-04-11T17:50:33.281259Z","end":"2026-04-11T17:50:33.487399Z","steps":["trace[1325273239] 'process raft request' (duration: 205.870612ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:55.404432Z","caller":"traceutil/trace.go:171","msg":"trace[330210021] transaction","detail":"{read_only:false; response_revision:1096; number_of_response:1; }","duration":"184.682156ms","start":"2026-04-11T17:50:55.219717Z","end":"2026-04-11T17:50:55.404399Z","steps":["trace[330210021] 'process raft request' (duration: 184.512271ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.140929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.128147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:50:56.14102Z","caller":"traceutil/trace.go:171","msg":"trace[929269306] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1096; }","duration":"435.278481ms","start":"2026-04-11T17:50:55.705721Z","end":"2026-04-11T17:50:56.140999Z","steps":["trace[929269306] 'range keys from in-memory index tree' (duration: 434.950094ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.141063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.705693Z","time spent":"435.360645ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T17:50:56.141104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.479541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:50:56.141179Z","caller":"traceutil/trace.go:171","msg":"trace[170056028] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1096; }","duration":"418.610115ms","start":"2026-04-11T17:50:55.722551Z","end":"2026-04-11T17:50:56.141161Z","steps":["trace[170056028] 'count revisions from in-memory index tree' (duration: 418.391589ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.141219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.722535Z","time spent":"418.675656ms","remote":"127.0.0.1:60860","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:50:56.141105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"625.524118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:50:56.141483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.12331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:50:56.141512Z","caller":"traceutil/trace.go:171","msg":"trace[1887093768] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1096; }","duration":"425.178331ms","start":"2026-04-11T17:50:55.716326Z","end":"2026-04-11T17:50:56.141505Z","steps":["trace[1887093768] 'range keys from in-memory index tree' (duration: 424.994495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.141468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"430.35639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T17:50:56.141538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.716313Z","time spent":"425.220562ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:50:56.141497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"578.928787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:50:56.141578Z","caller":"traceutil/trace.go:171","msg":"trace[255509073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1096; }","duration":"430.501205ms","start":"2026-04-11T17:50:55.711047Z","end":"2026-04-11T17:50:56.141549Z","steps":["trace[255509073] 'range keys from in-memory index tree' (duration: 430.127864ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.141637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.711036Z","time spent":"430.591096ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T17:50:56.141644Z","caller":"traceutil/trace.go:171","msg":"trace[1737795200] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1096; }","duration":"579.118743ms","start":"2026-04-11T17:50:55.562458Z","end":"2026-04-11T17:50:56.141577Z","steps":["trace[1737795200] 'range keys from in-memory index tree' (duration: 578.420344ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:56.141505Z","caller":"traceutil/trace.go:171","msg":"trace[476355016] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1096; }","duration":"625.93007ms","start":"2026-04-11T17:50:55.515504Z","end":"2026-04-11T17:50:56.141434Z","steps":["trace[476355016] 'range keys from in-memory index tree' (duration: 625.389134ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.141765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.515476Z","time spent":"626.246618ms","remote":"127.0.0.1:60480","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-04-11T17:50:56.141794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.562432Z","time spent":"579.346769ms","remote":"127.0.0.1:60726","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-04-11T17:50:56.141919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"523.90498ms","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":"info","ts":"2026-04-11T17:50:56.141981Z","caller":"traceutil/trace.go:171","msg":"trace[771541188] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1096; }","duration":"524.063575ms","start":"2026-04-11T17:50:55.617902Z","end":"2026-04-11T17:50:56.141965Z","steps":["trace[771541188] 'count revisions from in-memory index tree' (duration: 523.747027ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:50:56.142019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:50:55.617882Z","time spent":"524.126087ms","remote":"127.0.0.1:60572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":9,"response size":30,"request content":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:50:56.315604Z","caller":"traceutil/trace.go:171","msg":"trace[1053294147] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"167.626149ms","start":"2026-04-11T17:50:56.147941Z","end":"2026-04-11T17:50:56.315567Z","steps":["trace[1053294147] 'process raft request' (duration: 110.571648ms)","trace[1053294147] 'compare' (duration: 56.814754ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:50:56.316003Z","caller":"traceutil/trace.go:171","msg":"trace[1717319666] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"167.908516ms","start":"2026-04-11T17:50:56.148033Z","end":"2026-04-11T17:50:56.315941Z","steps":["trace[1717319666] 'process raft request' (duration: 167.463904ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:56.316068Z","caller":"traceutil/trace.go:171","msg":"trace[707330587] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"167.201448ms","start":"2026-04-11T17:50:56.148826Z","end":"2026-04-11T17:50:56.316027Z","steps":["trace[707330587] 'process raft request' (duration: 166.978892ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:56.316123Z","caller":"traceutil/trace.go:171","msg":"trace[1803790485] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"138.720453ms","start":"2026-04-11T17:50:56.177389Z","end":"2026-04-11T17:50:56.31611Z","steps":["trace[1803790485] 'process raft request' (duration: 138.573299ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:56.31604Z","caller":"traceutil/trace.go:171","msg":"trace[896634763] linearizableReadLoop","detail":"{readStateIndex:1149; appliedIndex:1145; }","duration":"126.415623ms","start":"2026-04-11T17:50:56.189599Z","end":"2026-04-11T17:50:56.316015Z","steps":["trace[896634763] 'read index received' (duration: 68.845198ms)","trace[896634763] 'applied index is now lower than readState.Index' (duration: 57.569155ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:50:56.316253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.633719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-11T17:50:56.316301Z","caller":"traceutil/trace.go:171","msg":"trace[1013787990] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1101; }","duration":"126.733171ms","start":"2026-04-11T17:50:56.189557Z","end":"2026-04-11T17:50:56.31629Z","steps":["trace[1013787990] 'agreement among raft nodes before linearized reading' (duration: 126.541766ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:50:57.75263Z","caller":"traceutil/trace.go:171","msg":"trace[1521393404] transaction","detail":"{read_only:false; response_revision:1103; number_of_response:1; }","duration":"230.172317ms","start":"2026-04-11T17:50:57.522429Z","end":"2026-04-11T17:50:57.752601Z","steps":["trace[1521393404] 'process raft request' (duration: 229.961872ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:00.543486Z","caller":"traceutil/trace.go:171","msg":"trace[499624045] linearizableReadLoop","detail":"{readStateIndex:1160; appliedIndex:1159; }","duration":"174.71745ms","start":"2026-04-11T17:51:00.36874Z","end":"2026-04-11T17:51:00.543458Z","steps":["trace[499624045] 'read index received' (duration: 174.517694ms)","trace[499624045] 'applied index is now lower than readState.Index' (duration: 198.116µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:00.54364Z","caller":"traceutil/trace.go:171","msg":"trace[1262171916] transaction","detail":"{read_only:false; response_revision:1111; number_of_response:1; }","duration":"177.293678ms","start":"2026-04-11T17:51:00.366334Z","end":"2026-04-11T17:51:00.543628Z","steps":["trace[1262171916] 'process raft request' (duration: 176.897937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:00.54395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.1531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:51:00.543989Z","caller":"traceutil/trace.go:171","msg":"trace[591062720] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1111; }","duration":"175.274144ms","start":"2026-04-11T17:51:00.3687Z","end":"2026-04-11T17:51:00.543975Z","steps":["trace[591062720] 'agreement among raft nodes before linearized reading' (duration: 175.0815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:00.543976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.696349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:00.54403Z","caller":"traceutil/trace.go:171","msg":"trace[1061647589] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1111; }","duration":"174.779071ms","start":"2026-04-11T17:51:00.369238Z","end":"2026-04-11T17:51:00.544017Z","steps":["trace[1061647589] 'agreement among raft nodes before linearized reading' (duration: 174.596015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:00.544169Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.32415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:51:00.544193Z","caller":"traceutil/trace.go:171","msg":"trace[408079627] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1111; }","duration":"107.370082ms","start":"2026-04-11T17:51:00.436816Z","end":"2026-04-11T17:51:00.544186Z","steps":["trace[408079627] 'agreement among raft nodes before linearized reading' (duration: 107.2912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:00.544448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.877894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:00.544642Z","caller":"traceutil/trace.go:171","msg":"trace[454530056] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1111; }","duration":"175.12144ms","start":"2026-04-11T17:51:00.369474Z","end":"2026-04-11T17:51:00.544595Z","steps":["trace[454530056] 'agreement among raft nodes before linearized reading' (duration: 174.640987ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.121548Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290241,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:51:13.547787Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.165796638s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:51:13.548344Z","caller":"traceutil/trace.go:171","msg":"trace[1980425] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"1.166441885s","start":"2026-04-11T17:51:12.381869Z","end":"2026-04-11T17:51:13.548311Z","steps":["trace[1980425] 'process raft request' (duration: 1.166169979s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.548558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.381851Z","time spent":"1.16658827s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:13.55759Z","caller":"traceutil/trace.go:171","msg":"trace[960521717] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1253; }","duration":"936.314649ms","start":"2026-04-11T17:51:12.621254Z","end":"2026-04-11T17:51:13.557569Z","steps":["trace[960521717] 'read index received' (duration: 926.822384ms)","trace[960521717] 'applied index is now lower than readState.Index' (duration: 9.491215ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:13.557664Z","caller":"traceutil/trace.go:171","msg":"trace[230524393] transaction","detail":"{read_only:false; response_revision:1202; number_of_response:1; }","duration":"957.952211ms","start":"2026-04-11T17:51:12.599702Z","end":"2026-04-11T17:51:13.557655Z","steps":["trace[230524393] 'process raft request' (duration: 957.745635ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.557813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"512.942597ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:51:13.557844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.641107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:51:13.557889Z","caller":"traceutil/trace.go:171","msg":"trace[1981457191] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1202; }","duration":"513.07265ms","start":"2026-04-11T17:51:13.044797Z","end":"2026-04-11T17:51:13.55787Z","steps":["trace[1981457191] 'agreement among raft nodes before linearized reading' (duration: 512.918236ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:13.557901Z","caller":"traceutil/trace.go:171","msg":"trace[294180593] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1202; }","duration":"911.73331ms","start":"2026-04-11T17:51:12.646152Z","end":"2026-04-11T17:51:13.557885Z","steps":["trace[294180593] 'agreement among raft nodes before linearized reading' (duration: 911.538504ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.557901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.790555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-04-11T17:51:13.557927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:13.044772Z","time spent":"513.149131ms","remote":"127.0.0.1:60480","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-04-11T17:51:13.557931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.646134Z","time spent":"911.791931ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T17:51:13.557936Z","caller":"traceutil/trace.go:171","msg":"trace[619926254] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1202; }","duration":"911.052291ms","start":"2026-04-11T17:51:12.646875Z","end":"2026-04-11T17:51:13.557927Z","steps":["trace[619926254] 'agreement among raft nodes before linearized reading' (duration: 910.912138ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.557955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.646853Z","time spent":"911.098283ms","remote":"127.0.0.1:60726","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-04-11T17:51:13.557951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"379.039724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T17:51:13.557984Z","caller":"traceutil/trace.go:171","msg":"trace[863414847] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1202; }","duration":"379.099574ms","start":"2026-04-11T17:51:13.178875Z","end":"2026-04-11T17:51:13.557974Z","steps":["trace[863414847] 'agreement among raft nodes before linearized reading' (duration: 378.94839ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.557969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"936.684008ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:51:13.558054Z","caller":"traceutil/trace.go:171","msg":"trace[968534683] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1202; }","duration":"936.813362ms","start":"2026-04-11T17:51:12.62122Z","end":"2026-04-11T17:51:13.558033Z","steps":["trace[968534683] 'agreement among raft nodes before linearized reading' (duration: 936.565896ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.558116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.621205Z","time spent":"936.895355ms","remote":"127.0.0.1:60726","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-04-11T17:51:13.558008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:13.178856Z","time spent":"379.147786ms","remote":"127.0.0.1:60492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"warn","ts":"2026-04-11T17:51:13.558156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.00697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:13.55825Z","caller":"traceutil/trace.go:171","msg":"trace[1961657009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1202; }","duration":"911.336369ms","start":"2026-04-11T17:51:12.646891Z","end":"2026-04-11T17:51:13.558227Z","steps":["trace[1961657009] 'agreement among raft nodes before linearized reading' (duration: 910.99311ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:13.558297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.646876Z","time spent":"911.414212ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:13.557775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:12.59967Z","time spent":"958.022571ms","remote":"127.0.0.1:60726","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-04-11T17:51:25.81726Z","caller":"traceutil/trace.go:171","msg":"trace[2059890702] linearizableReadLoop","detail":"{readStateIndex:1297; appliedIndex:1296; }","duration":"202.946276ms","start":"2026-04-11T17:51:25.614295Z","end":"2026-04-11T17:51:25.817242Z","steps":["trace[2059890702] 'read index received' (duration: 202.771902ms)","trace[2059890702] 'applied index is now lower than readState.Index' (duration: 173.404µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:25.817665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.461633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:51:25.817735Z","caller":"traceutil/trace.go:171","msg":"trace[1783854254] transaction","detail":"{read_only:false; response_revision:1241; number_of_response:1; }","duration":"206.526244ms","start":"2026-04-11T17:51:25.611196Z","end":"2026-04-11T17:51:25.817722Z","steps":["trace[1783854254] 'process raft request' (duration: 205.929937ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:25.817811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.938212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:25.817881Z","caller":"traceutil/trace.go:171","msg":"trace[576748042] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1241; }","duration":"176.036405ms","start":"2026-04-11T17:51:25.641825Z","end":"2026-04-11T17:51:25.817861Z","steps":["trace[576748042] 'agreement among raft nodes before linearized reading' (duration: 175.83649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:25.818007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.722908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:51:25.818031Z","caller":"traceutil/trace.go:171","msg":"trace[1338871938] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1241; }","duration":"203.77774ms","start":"2026-04-11T17:51:25.614245Z","end":"2026-04-11T17:51:25.818023Z","steps":["trace[1338871938] 'agreement among raft nodes before linearized reading' (duration: 203.714638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:25.817994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.168769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:51:25.818097Z","caller":"traceutil/trace.go:171","msg":"trace[159262431] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1241; }","duration":"176.339493ms","start":"2026-04-11T17:51:25.641731Z","end":"2026-04-11T17:51:25.81807Z","steps":["trace[159262431] 'agreement among raft nodes before linearized reading' (duration: 176.033434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:25.818116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.607854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:25.818138Z","caller":"traceutil/trace.go:171","msg":"trace[803663482] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1241; }","duration":"178.642995ms","start":"2026-04-11T17:51:25.63949Z","end":"2026-04-11T17:51:25.818133Z","steps":["trace[803663482] 'agreement among raft nodes before linearized reading' (duration: 178.589523ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.818259Z","caller":"traceutil/trace.go:171","msg":"trace[620459750] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1241; }","duration":"184.106702ms","start":"2026-04-11T17:51:25.634147Z","end":"2026-04-11T17:51:25.818253Z","steps":["trace[620459750] 'agreement among raft nodes before linearized reading' (duration: 183.415553ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.963228Z","caller":"traceutil/trace.go:171","msg":"trace[1036543862] transaction","detail":"{read_only:false; response_revision:1242; number_of_response:1; }","duration":"141.194279ms","start":"2026-04-11T17:51:25.822006Z","end":"2026-04-11T17:51:25.9632Z","steps":["trace[1036543862] 'process raft request' (duration: 141.059025ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.986984Z","caller":"traceutil/trace.go:171","msg":"trace[2099354094] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"163.99168ms","start":"2026-04-11T17:51:25.822972Z","end":"2026-04-11T17:51:25.986964Z","steps":["trace[2099354094] 'process raft request' (duration: 163.279152ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.98715Z","caller":"traceutil/trace.go:171","msg":"trace[1964557373] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"149.755949ms","start":"2026-04-11T17:51:25.837382Z","end":"2026-04-11T17:51:25.987138Z","steps":["trace[1964557373] 'process raft request' (duration: 149.110312ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.987111Z","caller":"traceutil/trace.go:171","msg":"trace[2012489130] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"163.591741ms","start":"2026-04-11T17:51:25.823462Z","end":"2026-04-11T17:51:25.987054Z","steps":["trace[2012489130] 'process raft request' (duration: 162.912133ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.987249Z","caller":"traceutil/trace.go:171","msg":"trace[1414134014] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"158.055903ms","start":"2026-04-11T17:51:25.829102Z","end":"2026-04-11T17:51:25.987158Z","steps":["trace[1414134014] 'process raft request' (duration: 157.354904ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:25.987554Z","caller":"traceutil/trace.go:171","msg":"trace[102055242] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"159.969554ms","start":"2026-04-11T17:51:25.827559Z","end":"2026-04-11T17:51:25.987529Z","steps":["trace[102055242] 'process raft request' (duration: 158.857964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:26.230229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.113591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:26.230328Z","caller":"traceutil/trace.go:171","msg":"trace[1982181411] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"184.294885ms","start":"2026-04-11T17:51:26.046011Z","end":"2026-04-11T17:51:26.230306Z","steps":["trace[1982181411] 'range keys from in-memory index tree' (duration: 183.996558ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:28.136805Z","caller":"traceutil/trace.go:171","msg":"trace[410048540] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"168.659197ms","start":"2026-04-11T17:51:27.968107Z","end":"2026-04-11T17:51:28.136766Z","steps":["trace[410048540] 'read index received' (duration: 168.413971ms)","trace[410048540] 'applied index is now lower than readState.Index' (duration: 244.026µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:28.137408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.300294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:51:28.13748Z","caller":"traceutil/trace.go:171","msg":"trace[1509479119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1256; }","duration":"169.454588ms","start":"2026-04-11T17:51:27.968012Z","end":"2026-04-11T17:51:28.137466Z","steps":["trace[1509479119] 'agreement among raft nodes before linearized reading' (duration: 169.222582ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:28.137719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.012887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:28.137808Z","caller":"traceutil/trace.go:171","msg":"trace[611238555] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"313.805751ms","start":"2026-04-11T17:51:27.823947Z","end":"2026-04-11T17:51:28.137753Z","steps":["trace[611238555] 'process raft request' (duration: 312.466966ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:28.137982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.998081ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T17:51:28.138024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.238419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:28.138045Z","caller":"traceutil/trace.go:171","msg":"trace[967805932] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1256; }","duration":"145.117515ms","start":"2026-04-11T17:51:27.99291Z","end":"2026-04-11T17:51:28.138028Z","steps":["trace[967805932] 'agreement among raft nodes before linearized reading' (duration: 144.980792ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:28.138058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.152226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:51:28.138071Z","caller":"traceutil/trace.go:171","msg":"trace[1399170922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1256; }","duration":"145.30849ms","start":"2026-04-11T17:51:27.99275Z","end":"2026-04-11T17:51:28.138058Z","steps":["trace[1399170922] 'agreement among raft nodes before linearized reading' (duration: 145.179106ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:28.138118Z","caller":"traceutil/trace.go:171","msg":"trace[1003269929] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1256; }","duration":"145.272749ms","start":"2026-04-11T17:51:27.992832Z","end":"2026-04-11T17:51:28.138104Z","steps":["trace[1003269929] 'agreement among raft nodes before linearized reading' (duration: 145.093254ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:28.13848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:27.823916Z","time spent":"314.375018ms","remote":"127.0.0.1:60726","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-04-11T17:51:28.138269Z","caller":"traceutil/trace.go:171","msg":"trace[96748422] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1256; }","duration":"146.282186ms","start":"2026-04-11T17:51:27.991542Z","end":"2026-04-11T17:51:28.137825Z","steps":["trace[96748422] 'agreement among raft nodes before linearized reading' (duration: 145.829124ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:29.164244Z","caller":"traceutil/trace.go:171","msg":"trace[1934773236] transaction","detail":"{read_only:false; response_revision:1273; number_of_response:1; }","duration":"185.204181ms","start":"2026-04-11T17:51:28.979009Z","end":"2026-04-11T17:51:29.164214Z","steps":["trace[1934773236] 'process raft request' (duration: 185.009916ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:30.618253Z","caller":"traceutil/trace.go:171","msg":"trace[717892957] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"471.929875ms","start":"2026-04-11T17:51:30.146295Z","end":"2026-04-11T17:51:30.618225Z","steps":["trace[717892957] 'process raft request' (duration: 471.75927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.618436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.14623Z","time spent":"472.108651ms","remote":"127.0.0.1:60726","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-04-11T17:51:30.618556Z","caller":"traceutil/trace.go:171","msg":"trace[190667453] linearizableReadLoop","detail":"{readStateIndex:1332; appliedIndex:1332; }","duration":"450.066169ms","start":"2026-04-11T17:51:30.168443Z","end":"2026-04-11T17:51:30.618509Z","steps":["trace[190667453] 'read index received' (duration: 450.050469ms)","trace[190667453] 'applied index is now lower than readState.Index' (duration: 11.4µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:30.619054Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.828669ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:51:30.619051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.428373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:30.619127Z","caller":"traceutil/trace.go:171","msg":"trace[1786681617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1275; }","duration":"425.970672ms","start":"2026-04-11T17:51:30.193137Z","end":"2026-04-11T17:51:30.619108Z","steps":["trace[1786681617] 'agreement among raft nodes before linearized reading' (duration: 425.575981ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:30.619147Z","caller":"traceutil/trace.go:171","msg":"trace[2037499879] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1275; }","duration":"423.548007ms","start":"2026-04-11T17:51:30.195574Z","end":"2026-04-11T17:51:30.619122Z","steps":["trace[2037499879] 'agreement among raft nodes before linearized reading' (duration: 423.156687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.619162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.724496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:51:30.619188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.193125Z","time spent":"426.047324ms","remote":"127.0.0.1:60726","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-04-11T17:51:30.619211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.195561Z","time spent":"423.63207ms","remote":"127.0.0.1:60726","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-04-11T17:51:30.61923Z","caller":"traceutil/trace.go:171","msg":"trace[612660874] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:1275; }","duration":"450.83728ms","start":"2026-04-11T17:51:30.168377Z","end":"2026-04-11T17:51:30.619215Z","steps":["trace[612660874] 'agreement among raft nodes before linearized reading' (duration: 450.733107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.619273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.168362Z","time spent":"450.89778ms","remote":"127.0.0.1:60264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:51:30.619388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"424.216826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:51:30.619479Z","caller":"traceutil/trace.go:171","msg":"trace[567041258] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1275; }","duration":"424.324138ms","start":"2026-04-11T17:51:30.195139Z","end":"2026-04-11T17:51:30.619463Z","steps":["trace[567041258] 'agreement among raft nodes before linearized reading' (duration: 424.063631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.619528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.845619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T17:51:30.619765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.477314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:30.619775Z","caller":"traceutil/trace.go:171","msg":"trace[546536231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1275; }","duration":"426.152657ms","start":"2026-04-11T17:51:30.193609Z","end":"2026-04-11T17:51:30.619762Z","steps":["trace[546536231] 'agreement among raft nodes before linearized reading' (duration: 425.814348ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:30.619813Z","caller":"traceutil/trace.go:171","msg":"trace[1501386710] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1275; }","duration":"300.743771ms","start":"2026-04-11T17:51:30.319057Z","end":"2026-04-11T17:51:30.619801Z","steps":["trace[1501386710] 'agreement among raft nodes before linearized reading' (duration: 300.480204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.619854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.31904Z","time spent":"300.804593ms","remote":"127.0.0.1:60184","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:51:30.619969Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.195127Z","time spent":"424.699958ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-11T17:51:30.619045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.048188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:30.620179Z","caller":"traceutil/trace.go:171","msg":"trace[1905532546] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1275; }","duration":"426.217289ms","start":"2026-04-11T17:51:30.193924Z","end":"2026-04-11T17:51:30.620142Z","steps":["trace[1905532546] 'agreement among raft nodes before linearized reading' (duration: 424.872422ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:30.620325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.193914Z","time spent":"426.361203ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:30.620357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.193581Z","time spent":"426.754612ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:31.374917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.849704ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:51:31.375184Z","caller":"traceutil/trace.go:171","msg":"trace[1855274092] transaction","detail":"{read_only:false; response_revision:1280; number_of_response:1; }","duration":"748.778925ms","start":"2026-04-11T17:51:30.626385Z","end":"2026-04-11T17:51:31.375164Z","steps":["trace[1855274092] 'process raft request' (duration: 748.727994ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:31.37525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.626374Z","time spent":"748.844588ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:31.375384Z","caller":"traceutil/trace.go:171","msg":"trace[1324156850] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"749.859246ms","start":"2026-04-11T17:51:30.625471Z","end":"2026-04-11T17:51:31.375331Z","steps":["trace[1324156850] 'process raft request' (duration: 293.484887ms)","trace[1324156850] 'compare' (duration: 455.716241ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:31.375395Z","caller":"traceutil/trace.go:171","msg":"trace[65374030] transaction","detail":"{read_only:false; response_revision:1279; number_of_response:1; }","duration":"749.114405ms","start":"2026-04-11T17:51:30.626246Z","end":"2026-04-11T17:51:31.37536Z","steps":["trace[65374030] 'process raft request' (duration: 748.833508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:31.375624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.625418Z","time spent":"750.07816ms","remote":"127.0.0.1:60726","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-04-11T17:51:31.375396Z","caller":"traceutil/trace.go:171","msg":"trace[30257986] transaction","detail":"{read_only:false; response_revision:1278; number_of_response:1; }","duration":"749.164536ms","start":"2026-04-11T17:51:30.626194Z","end":"2026-04-11T17:51:31.375359Z","steps":["trace[30257986] 'process raft request' (duration: 748.841908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:31.375705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.626234Z","time spent":"749.360911ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:51:31.375786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:30.626185Z","time spent":"749.510585ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:33.011354Z","caller":"traceutil/trace.go:171","msg":"trace[248944045] transaction","detail":"{read_only:false; response_revision:1282; number_of_response:1; }","duration":"436.442644ms","start":"2026-04-11T17:51:32.574855Z","end":"2026-04-11T17:51:33.011298Z","steps":["trace[248944045] 'process raft request' (duration: 436.241749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:33.011528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.574834Z","time spent":"436.609028ms","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:51:33.490727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.212403ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:51:33.490848Z","caller":"traceutil/trace.go:171","msg":"trace[2053789911] linearizableReadLoop","detail":"{readStateIndex:1340; appliedIndex:1338; }","duration":"832.534693ms","start":"2026-04-11T17:51:32.658295Z","end":"2026-04-11T17:51:33.490829Z","steps":["trace[2053789911] 'read index received' (duration: 352.822119ms)","trace[2053789911] 'applied index is now lower than readState.Index' (duration: 479.711294ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:33.490928Z","caller":"traceutil/trace.go:171","msg":"trace[1391888563] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"864.608345ms","start":"2026-04-11T17:51:32.626308Z","end":"2026-04-11T17:51:33.490916Z","steps":["trace[1391888563] 'process raft request' (duration: 445.99508ms)","trace[1391888563] 'compare' (duration: 417.971358ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:33.491004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.626287Z","time spent":"864.669887ms","remote":"127.0.0.1:60726","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-04-11T17:51:33.491317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"832.993516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:33.491469Z","caller":"traceutil/trace.go:171","msg":"trace[816465262] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1283; }","duration":"833.170401ms","start":"2026-04-11T17:51:32.658259Z","end":"2026-04-11T17:51:33.491429Z","steps":["trace[816465262] 'agreement among raft nodes before linearized reading' (duration: 832.952355ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:33.491523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.658245Z","time spent":"833.265784ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-04-11T17:51:33.991628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290415,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-11T17:51:34.304528Z","caller":"traceutil/trace.go:171","msg":"trace[1827463129] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"1.180343289s","start":"2026-04-11T17:51:33.124152Z","end":"2026-04-11T17:51:34.304495Z","steps":["trace[1827463129] 'process raft request' (duration: 1.180141083s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.304753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.124132Z","time spent":"1.180517603s","remote":"127.0.0.1:60546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-11T17:51:34.381093Z","caller":"traceutil/trace.go:171","msg":"trace[239455805] transaction","detail":"{read_only:false; response_revision:1285; number_of_response:1; }","duration":"883.988376ms","start":"2026-04-11T17:51:33.497067Z","end":"2026-04-11T17:51:34.381056Z","steps":["trace[239455805] 'process raft request' (duration: 830.366586ms)","trace[239455805] 'compare' (duration: 53.346242ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:34.381252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.497042Z","time spent":"884.1313ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-11T17:51:34.381348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"999.235708ms","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-04-11T17:51:34.38142Z","caller":"traceutil/trace.go:171","msg":"trace[2063549117] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1286; }","duration":"999.401553ms","start":"2026-04-11T17:51:33.382005Z","end":"2026-04-11T17:51:34.381407Z","steps":["trace[2063549117] 'agreement among raft nodes before linearized reading' (duration: 999.131695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.381462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.38198Z","time spent":"999.475904ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:34.38151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.335950625s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:51:34.381595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.639012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-11T17:51:34.381622Z","caller":"traceutil/trace.go:171","msg":"trace[1945520284] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1286; }","duration":"998.680853ms","start":"2026-04-11T17:51:33.382931Z","end":"2026-04-11T17:51:34.381612Z","steps":["trace[1945520284] 'agreement among raft nodes before linearized reading' (duration: 998.627631ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.381638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.382919Z","time spent":"998.714945ms","remote":"127.0.0.1:60726","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-04-11T17:51:34.381636Z","caller":"traceutil/trace.go:171","msg":"trace[546271885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1286; }","duration":"1.33616364s","start":"2026-04-11T17:51:33.045445Z","end":"2026-04-11T17:51:34.381609Z","steps":["trace[546271885] 'agreement among raft nodes before linearized reading' (duration: 1.335870692s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.381698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.045421Z","time spent":"1.336268464s","remote":"127.0.0.1:60480","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-04-11T17:51:34.381902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.527377792s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:34.381938Z","caller":"traceutil/trace.go:171","msg":"trace[2045515558] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1286; }","duration":"1.527446596s","start":"2026-04-11T17:51:32.854465Z","end":"2026-04-11T17:51:34.381912Z","steps":["trace[2045515558] 'agreement among raft nodes before linearized reading' (duration: 1.52722712s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.381919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"997.658846ms","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-04-11T17:51:34.381959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.854439Z","time spent":"1.527515386s","remote":"127.0.0.1:60136","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:51:34.381924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.201592279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.246\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-11T17:51:34.381986Z","caller":"traceutil/trace.go:171","msg":"trace[538484487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1286; }","duration":"997.980715ms","start":"2026-04-11T17:51:33.383989Z","end":"2026-04-11T17:51:34.38197Z","steps":["trace[538484487] 'agreement among raft nodes before linearized reading' (duration: 997.537993ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.383962Z","time spent":"998.055686ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-11T17:51:34.382032Z","caller":"traceutil/trace.go:171","msg":"trace[862075732] range","detail":"{range_begin:/registry/masterleases/199.204.45.246; range_end:; response_count:1; response_revision:1286; }","duration":"1.201970658s","start":"2026-04-11T17:51:33.180033Z","end":"2026-04-11T17:51:34.382003Z","steps":["trace[862075732] 'agreement among raft nodes before linearized reading' (duration: 1.201414953s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.572045022s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:51:34.382089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.179998Z","time spent":"1.202081073s","remote":"127.0.0.1:60492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.246\" "} {"level":"info","ts":"2026-04-11T17:51:34.38213Z","caller":"traceutil/trace.go:171","msg":"trace[1377086083] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:1286; }","duration":"1.572205965s","start":"2026-04-11T17:51:32.809893Z","end":"2026-04-11T17:51:34.382099Z","steps":["trace[1377086083] 'agreement among raft nodes before linearized reading' (duration: 1.572032581s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.809872Z","time spent":"1.572305719s","remote":"127.0.0.1:60130","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":"info","ts":"2026-04-11T17:51:34.382246Z","caller":"traceutil/trace.go:171","msg":"trace[532617684] linearizableReadLoop","detail":"{readStateIndex:1342; appliedIndex:1340; }","duration":"890.227832ms","start":"2026-04-11T17:51:33.490877Z","end":"2026-04-11T17:51:34.381104Z","steps":["trace[532617684] 'read index received' (duration: 813.394041ms)","trace[532617684] 'applied index is now lower than readState.Index' (duration: 76.829901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:34.382335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.669389484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-11T17:51:34.382352Z","caller":"traceutil/trace.go:171","msg":"trace[1059647811] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1286; }","duration":"1.669505448s","start":"2026-04-11T17:51:32.712841Z","end":"2026-04-11T17:51:34.382346Z","steps":["trace[1059647811] 'agreement among raft nodes before linearized reading' (duration: 1.669448696s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:32.71282Z","time spent":"1.669543149s","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-11T17:51:34.382556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.226433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:34.382578Z","caller":"traceutil/trace.go:171","msg":"trace[884390426] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:1286; }","duration":"178.291885ms","start":"2026-04-11T17:51:34.20428Z","end":"2026-04-11T17:51:34.382572Z","steps":["trace[884390426] 'agreement among raft nodes before linearized reading' (duration: 178.236073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382612Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.758274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-11T17:51:34.382659Z","caller":"traceutil/trace.go:171","msg":"trace[1272473935] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1286; }","duration":"138.845796ms","start":"2026-04-11T17:51:34.243799Z","end":"2026-04-11T17:51:34.382645Z","steps":["trace[1272473935] 'agreement among raft nodes before linearized reading' (duration: 138.722373ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.382986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"998.269702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-11T17:51:34.383035Z","caller":"traceutil/trace.go:171","msg":"trace[1660471453] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1286; }","duration":"998.92338ms","start":"2026-04-11T17:51:33.384099Z","end":"2026-04-11T17:51:34.383023Z","steps":["trace[1660471453] 'agreement among raft nodes before linearized reading' (duration: 998.110208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:34.38306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:33.384063Z","time spent":"998.989202ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-11T17:51:34.547241Z","caller":"traceutil/trace.go:171","msg":"trace[7135087] transaction","detail":"{read_only:false; response_revision:1293; number_of_response:1; }","duration":"152.322488ms","start":"2026-04-11T17:51:34.394869Z","end":"2026-04-11T17:51:34.547191Z","steps":["trace[7135087] 'process raft request' (duration: 95.204425ms)","trace[7135087] 'compare' (duration: 56.941759ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:34.547728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.334806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:34.547811Z","caller":"traceutil/trace.go:171","msg":"trace[1419726903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1294; }","duration":"156.461909ms","start":"2026-04-11T17:51:34.391331Z","end":"2026-04-11T17:51:34.547793Z","steps":["trace[1419726903] 'agreement among raft nodes before linearized reading' (duration: 156.242123ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.01458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:51:36.514746Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290435,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:51:36.55099Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.053028071s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:51:36.551628Z","caller":"traceutil/trace.go:171","msg":"trace[1214411394] transaction","detail":"{read_only:false; response_revision:1295; number_of_response:1; }","duration":"1.053789712s","start":"2026-04-11T17:51:35.497799Z","end":"2026-04-11T17:51:36.551588Z","steps":["trace[1214411394] 'process raft request' (duration: 1.053551206s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.551832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:35.497768Z","time spent":"1.053932886s","remote":"127.0.0.1:60726","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-04-11T17:51:36.572343Z","caller":"traceutil/trace.go:171","msg":"trace[1920660295] linearizableReadLoop","detail":"{readStateIndex:1354; appliedIndex:1352; }","duration":"1.057991595s","start":"2026-04-11T17:51:35.514323Z","end":"2026-04-11T17:51:36.572314Z","steps":["trace[1920660295] 'read index received' (duration: 1.036993812s)","trace[1920660295] 'applied index is now lower than readState.Index' (duration: 20.996023ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:36.572486Z","caller":"traceutil/trace.go:171","msg":"trace[1856921356] transaction","detail":"{read_only:false; response_revision:1296; number_of_response:1; }","duration":"423.991709ms","start":"2026-04-11T17:51:36.148483Z","end":"2026-04-11T17:51:36.572475Z","steps":["trace[1856921356] 'process raft request' (duration: 423.6708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.572584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"657.852066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-11T17:51:36.57263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.148454Z","time spent":"424.05956ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:36.572649Z","caller":"traceutil/trace.go:171","msg":"trace[1532862207] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1296; }","duration":"657.98039ms","start":"2026-04-11T17:51:35.914651Z","end":"2026-04-11T17:51:36.572631Z","steps":["trace[1532862207] 'agreement among raft nodes before linearized reading' (duration: 657.857865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.572691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:35.914627Z","time spent":"658.054201ms","remote":"127.0.0.1:60936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:51:36.573057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.60716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-11T17:51:36.573048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.058707523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:36.573092Z","caller":"traceutil/trace.go:171","msg":"trace[1631674848] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"527.694452ms","start":"2026-04-11T17:51:36.045387Z","end":"2026-04-11T17:51:36.573082Z","steps":["trace[1631674848] 'agreement among raft nodes before linearized reading' (duration: 527.643051ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.573123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.045353Z","time spent":"527.764145ms","remote":"127.0.0.1:60476","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-04-11T17:51:36.573123Z","caller":"traceutil/trace.go:171","msg":"trace[1426823418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1296; }","duration":"1.058829108s","start":"2026-04-11T17:51:35.514274Z","end":"2026-04-11T17:51:36.573103Z","steps":["trace[1426823418] 'agreement among raft nodes before linearized reading' (duration: 1.058725855s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:36.573166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:35.51426Z","time spent":"1.058899149s","remote":"127.0.0.1:60480","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-04-11T17:51:37.149393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.465625ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-11T17:51:37.149847Z","caller":"traceutil/trace.go:171","msg":"trace[572911313] linearizableReadLoop","detail":"{readStateIndex:1359; appliedIndex:1354; }","duration":"348.834942ms","start":"2026-04-11T17:51:36.800987Z","end":"2026-04-11T17:51:37.149822Z","steps":["trace[572911313] 'read index received' (duration: 347.578518ms)","trace[572911313] 'applied index is now lower than readState.Index' (duration: 1.255264ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:37.149992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.997896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:37.150034Z","caller":"traceutil/trace.go:171","msg":"trace[710051540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1301; }","duration":"349.092359ms","start":"2026-04-11T17:51:36.800928Z","end":"2026-04-11T17:51:37.15002Z","steps":["trace[710051540] 'agreement among raft nodes before linearized reading' (duration: 348.982807ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:37.15008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.800896Z","time spent":"349.172471ms","remote":"127.0.0.1:60480","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-04-11T17:51:37.150258Z","caller":"traceutil/trace.go:171","msg":"trace[1933937985] transaction","detail":"{read_only:false; response_revision:1298; number_of_response:1; }","duration":"569.762042ms","start":"2026-04-11T17:51:36.580451Z","end":"2026-04-11T17:51:37.150213Z","steps":["trace[1933937985] 'process raft request' (duration: 569.106734ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:37.150347Z","caller":"traceutil/trace.go:171","msg":"trace[262412053] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"567.624904ms","start":"2026-04-11T17:51:36.582705Z","end":"2026-04-11T17:51:37.15033Z","steps":["trace[262412053] 'process raft request' (duration: 567.074829ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:37.150349Z","caller":"traceutil/trace.go:171","msg":"trace[747069216] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"569.803403ms","start":"2026-04-11T17:51:36.58052Z","end":"2026-04-11T17:51:37.150324Z","steps":["trace[747069216] 'process raft request' (duration: 569.217537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:37.150514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.580515Z","time spent":"569.917796ms","remote":"127.0.0.1:60726","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-04-11T17:51:37.15032Z","caller":"traceutil/trace.go:171","msg":"trace[2050790442] transaction","detail":"{read_only:false; response_revision:1297; number_of_response:1; }","duration":"569.830623ms","start":"2026-04-11T17:51:36.580422Z","end":"2026-04-11T17:51:37.150252Z","steps":["trace[2050790442] 'process raft request' (duration: 31.385901ms)","trace[2050790442] 'compare' (duration: 537.081095ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:37.150677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.582682Z","time spent":"567.745578ms","remote":"127.0.0.1:60726","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-04-11T17:51:37.150735Z","caller":"traceutil/trace.go:171","msg":"trace[1111650495] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"570.391308ms","start":"2026-04-11T17:51:36.580328Z","end":"2026-04-11T17:51:37.150719Z","steps":["trace[1111650495] 'process raft request' (duration: 569.360921ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:37.15058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.580431Z","time spent":"569.921655ms","remote":"127.0.0.1:60726","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-04-11T17:51:37.150815Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.580302Z","time spent":"570.469211ms","remote":"127.0.0.1:60726","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-04-11T17:51:37.150837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:36.580413Z","time spent":"570.291025ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:38.963883Z","caller":"traceutil/trace.go:171","msg":"trace[708060654] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"406.194611ms","start":"2026-04-11T17:51:38.557664Z","end":"2026-04-11T17:51:38.963859Z","steps":["trace[708060654] 'process raft request' (duration: 406.041687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:38.964307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:38.557639Z","time spent":"406.570822ms","remote":"127.0.0.1:60726","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-04-11T17:51:39.295033Z","caller":"traceutil/trace.go:171","msg":"trace[1603671261] linearizableReadLoop","detail":"{readStateIndex:1361; appliedIndex:1360; }","duration":"419.042317ms","start":"2026-04-11T17:51:38.875965Z","end":"2026-04-11T17:51:39.295008Z","steps":["trace[1603671261] 'read index received' (duration: 88.148097ms)","trace[1603671261] 'applied index is now lower than readState.Index' (duration: 330.89309ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:39.295091Z","caller":"traceutil/trace.go:171","msg":"trace[871687186] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"411.724051ms","start":"2026-04-11T17:51:38.883328Z","end":"2026-04-11T17:51:39.295052Z","steps":["trace[871687186] 'process raft request' (duration: 411.529315ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:39.295317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.496527ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-04-11T17:51:39.295289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.261123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:39.295371Z","caller":"traceutil/trace.go:171","msg":"trace[33599898] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1303; }","duration":"138.62673ms","start":"2026-04-11T17:51:39.156731Z","end":"2026-04-11T17:51:39.295358Z","steps":["trace[33599898] 'agreement among raft nodes before linearized reading' (duration: 138.438426ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:39.295415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.564279ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:39.295436Z","caller":"traceutil/trace.go:171","msg":"trace[1204211217] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1303; }","duration":"419.478749ms","start":"2026-04-11T17:51:38.875894Z","end":"2026-04-11T17:51:39.295373Z","steps":["trace[1204211217] 'agreement among raft nodes before linearized reading' (duration: 419.227492ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:39.295518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.632351ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-04-11T17:51:39.295524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:38.875868Z","time spent":"419.641584ms","remote":"127.0.0.1:60078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"info","ts":"2026-04-11T17:51:39.295546Z","caller":"traceutil/trace.go:171","msg":"trace[685917220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1303; }","duration":"138.679372ms","start":"2026-04-11T17:51:39.156861Z","end":"2026-04-11T17:51:39.29554Z","steps":["trace[685917220] 'agreement among raft nodes before linearized reading' (duration: 138.61471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:39.29552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.671723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-04-11T17:51:39.295553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:38.883299Z","time spent":"411.869365ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-11T17:51:39.295458Z","caller":"traceutil/trace.go:171","msg":"trace[1607839550] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1303; }","duration":"138.627791ms","start":"2026-04-11T17:51:39.156821Z","end":"2026-04-11T17:51:39.295449Z","steps":["trace[1607839550] 'agreement among raft nodes before linearized reading' (duration: 138.490457ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:39.29562Z","caller":"traceutil/trace.go:171","msg":"trace[1162502329] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1303; }","duration":"138.848327ms","start":"2026-04-11T17:51:39.15675Z","end":"2026-04-11T17:51:39.295598Z","steps":["trace[1162502329] 'agreement among raft nodes before linearized reading' (duration: 138.59927ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:39.295411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.534419ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:51:39.295695Z","caller":"traceutil/trace.go:171","msg":"trace[1774280037] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1303; }","duration":"138.884177ms","start":"2026-04-11T17:51:39.1568Z","end":"2026-04-11T17:51:39.295684Z","steps":["trace[1774280037] 'agreement among raft nodes before linearized reading' (duration: 138.422245ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:39.401795Z","caller":"traceutil/trace.go:171","msg":"trace[588300960] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"100.117376ms","start":"2026-04-11T17:51:39.301653Z","end":"2026-04-11T17:51:39.401771Z","steps":["trace[588300960] 'process raft request' (duration: 100.073485ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:39.401871Z","caller":"traceutil/trace.go:171","msg":"trace[2073389705] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"100.696042ms","start":"2026-04-11T17:51:39.301141Z","end":"2026-04-11T17:51:39.401837Z","steps":["trace[2073389705] 'process raft request' (duration: 100.503907ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:39.401963Z","caller":"traceutil/trace.go:171","msg":"trace[337259699] transaction","detail":"{read_only:false; response_revision:1305; number_of_response:1; }","duration":"100.715934ms","start":"2026-04-11T17:51:39.301076Z","end":"2026-04-11T17:51:39.401792Z","steps":["trace[337259699] 'process raft request' (duration: 99.765489ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:39.402049Z","caller":"traceutil/trace.go:171","msg":"trace[367696765] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"100.881497ms","start":"2026-04-11T17:51:39.301148Z","end":"2026-04-11T17:51:39.402029Z","steps":["trace[367696765] 'process raft request' (duration: 100.543148ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:41.578273Z","caller":"traceutil/trace.go:171","msg":"trace[2140388698] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"167.422903ms","start":"2026-04-11T17:51:41.410826Z","end":"2026-04-11T17:51:41.578249Z","steps":["trace[2140388698] 'process raft request' (duration: 91.702201ms)","trace[2140388698] 'compare' (duration: 74.959341ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:41.591685Z","caller":"traceutil/trace.go:171","msg":"trace[172488292] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"179.998982ms","start":"2026-04-11T17:51:41.411651Z","end":"2026-04-11T17:51:41.59165Z","steps":["trace[172488292] 'process raft request' (duration: 179.94823ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:41.591881Z","caller":"traceutil/trace.go:171","msg":"trace[543744064] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"180.810263ms","start":"2026-04-11T17:51:41.411055Z","end":"2026-04-11T17:51:41.591865Z","steps":["trace[543744064] 'process raft request' (duration: 180.412432ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:41.59201Z","caller":"traceutil/trace.go:171","msg":"trace[1574411781] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"180.876225ms","start":"2026-04-11T17:51:41.411097Z","end":"2026-04-11T17:51:41.591973Z","steps":["trace[1574411781] 'process raft request' (duration: 180.462593ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:45.95812Z","caller":"traceutil/trace.go:171","msg":"trace[63835498] transaction","detail":"{read_only:false; response_revision:1361; number_of_response:1; }","duration":"361.662207ms","start":"2026-04-11T17:51:45.596435Z","end":"2026-04-11T17:51:45.958097Z","steps":["trace[63835498] 'process raft request' (duration: 361.304858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:45.958556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.596409Z","time spent":"362.010126ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:51:45.958583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.73437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-11T17:51:45.958652Z","caller":"traceutil/trace.go:171","msg":"trace[812809290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1361; }","duration":"351.842933ms","start":"2026-04-11T17:51:45.606791Z","end":"2026-04-11T17:51:45.958634Z","steps":["trace[812809290] 'agreement among raft nodes before linearized reading' (duration: 351.562467ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:45.958092Z","caller":"traceutil/trace.go:171","msg":"trace[1508144595] linearizableReadLoop","detail":"{readStateIndex:1421; appliedIndex:1420; }","duration":"351.210876ms","start":"2026-04-11T17:51:45.606839Z","end":"2026-04-11T17:51:45.958049Z","steps":["trace[1508144595] 'read index received' (duration: 350.794935ms)","trace[1508144595] 'applied index is now lower than readState.Index' (duration: 414.571µs)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:51:45.958704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.60678Z","time spent":"351.907615ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:45.958925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.191052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-11T17:51:45.958986Z","caller":"traceutil/trace.go:171","msg":"trace[1219124923] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1361; }","duration":"349.271244ms","start":"2026-04-11T17:51:45.609697Z","end":"2026-04-11T17:51:45.958968Z","steps":["trace[1219124923] 'agreement among raft nodes before linearized reading' (duration: 349.131281ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:45.958978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.622434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:51:45.95909Z","caller":"traceutil/trace.go:171","msg":"trace[1656761965] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1361; }","duration":"349.793348ms","start":"2026-04-11T17:51:45.609268Z","end":"2026-04-11T17:51:45.959062Z","steps":["trace[1656761965] 'agreement among raft nodes before linearized reading' (duration: 349.503351ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:45.959027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.609679Z","time spent":"349.336166ms","remote":"127.0.0.1:60726","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-04-11T17:51:45.959154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.609257Z","time spent":"349.881451ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-11T17:51:46.54712Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290560,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:51:47.048031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10836959766394290560,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-11T17:51:47.437228Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.471670252s","expected-duration":"1s"} {"level":"info","ts":"2026-04-11T17:51:47.437639Z","caller":"traceutil/trace.go:171","msg":"trace[391195052] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"1.472184596s","start":"2026-04-11T17:51:45.965424Z","end":"2026-04-11T17:51:47.437609Z","steps":["trace[391195052] 'process raft request' (duration: 1.472005442s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.437842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.965401Z","time spent":"1.472335169s","remote":"127.0.0.1:60726","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-04-11T17:51:47.612492Z","caller":"traceutil/trace.go:171","msg":"trace[1980706305] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"779.51366ms","start":"2026-04-11T17:51:46.832945Z","end":"2026-04-11T17:51:47.612459Z","steps":["trace[1980706305] 'process raft request' (duration: 779.455378ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:47.612514Z","caller":"traceutil/trace.go:171","msg":"trace[1637199919] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"1.64704224s","start":"2026-04-11T17:51:45.96542Z","end":"2026-04-11T17:51:47.612463Z","steps":["trace[1637199919] 'process raft request' (duration: 1.643699s)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:51:47.612557Z","caller":"traceutil/trace.go:171","msg":"trace[1747571608] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"1.647081091s","start":"2026-04-11T17:51:45.965426Z","end":"2026-04-11T17:51:47.612507Z","steps":["trace[1747571608] 'process raft request' (duration: 1.646944937s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.612707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.965369Z","time spent":"1.647235065s","remote":"127.0.0.1:60726","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-04-11T17:51:47.61278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:45.965401Z","time spent":"1.647251005s","remote":"127.0.0.1:60726","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-04-11T17:51:47.612747Z","caller":"traceutil/trace.go:171","msg":"trace[1157157457] linearizableReadLoop","detail":"{readStateIndex:1426; appliedIndex:1422; }","duration":"1.56622972s","start":"2026-04-11T17:51:46.046473Z","end":"2026-04-11T17:51:47.612703Z","steps":["trace[1157157457] 'read index received' (duration: 1.391224673s)","trace[1157157457] 'applied index is now lower than readState.Index' (duration: 175.002437ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:51:47.612918Z","caller":"traceutil/trace.go:171","msg":"trace[1936713202] transaction","detail":"{read_only:false; response_revision:1366; number_of_response:1; }","duration":"577.041814ms","start":"2026-04-11T17:51:47.035851Z","end":"2026-04-11T17:51:47.612893Z","steps":["trace[1936713202] 'process raft request' (duration: 576.574031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.613011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:47.035823Z","time spent":"577.144467ms","remote":"127.0.0.1:60726","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-04-11T17:51:47.613013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.566586491s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:51:47.613154Z","caller":"traceutil/trace.go:171","msg":"trace[391199326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1366; }","duration":"1.566749944s","start":"2026-04-11T17:51:46.046345Z","end":"2026-04-11T17:51:47.613095Z","steps":["trace[391199326] 'agreement among raft nodes before linearized reading' (duration: 1.566624171s)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.613196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:46.046312Z","time spent":"1.566876239s","remote":"127.0.0.1:60480","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-04-11T17:51:47.613316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:46.832907Z","time spent":"779.657594ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:51:47.613438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.278294ms","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:530"} {"level":"info","ts":"2026-04-11T17:51:47.61348Z","caller":"traceutil/trace.go:171","msg":"trace[1136326264] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1366; }","duration":"285.383457ms","start":"2026-04-11T17:51:47.328087Z","end":"2026-04-11T17:51:47.61347Z","steps":["trace[1136326264] 'agreement among raft nodes before linearized reading' (duration: 285.236703ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.613557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.010823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:51:47.613617Z","caller":"traceutil/trace.go:171","msg":"trace[433391200] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1366; }","duration":"183.164696ms","start":"2026-04-11T17:51:47.430438Z","end":"2026-04-11T17:51:47.613603Z","steps":["trace[433391200] 'agreement among raft nodes before linearized reading' (duration: 182.875399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.614911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"790.852106ms","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-04-11T17:51:47.615047Z","caller":"traceutil/trace.go:171","msg":"trace[249484702] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1366; }","duration":"791.049191ms","start":"2026-04-11T17:51:46.823985Z","end":"2026-04-11T17:51:47.615034Z","steps":["trace[249484702] 'agreement among raft nodes before linearized reading' (duration: 789.302295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:51:47.61541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:51:46.823942Z","time spent":"791.322687ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-04-11T17:52:10.206313Z","caller":"traceutil/trace.go:171","msg":"trace[1346966613] transaction","detail":"{read_only:false; response_revision:1474; number_of_response:1; }","duration":"128.24382ms","start":"2026-04-11T17:52:10.078047Z","end":"2026-04-11T17:52:10.206291Z","steps":["trace[1346966613] 'process raft request' (duration: 128.117807ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:12.305749Z","caller":"traceutil/trace.go:171","msg":"trace[1813619031] transaction","detail":"{read_only:false; response_revision:1481; number_of_response:1; }","duration":"226.875964ms","start":"2026-04-11T17:52:12.078841Z","end":"2026-04-11T17:52:12.305717Z","steps":["trace[1813619031] 'process raft request' (duration: 226.664528ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:13.88309Z","caller":"traceutil/trace.go:171","msg":"trace[1738364609] linearizableReadLoop","detail":"{readStateIndex:1555; appliedIndex:1554; }","duration":"122.611358ms","start":"2026-04-11T17:52:13.760438Z","end":"2026-04-11T17:52:13.88305Z","steps":["trace[1738364609] 'read index received' (duration: 120.719107ms)","trace[1738364609] 'applied index is now lower than readState.Index' (duration: 1.890901ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:52:13.883304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.841695ms","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:531"} {"level":"info","ts":"2026-04-11T17:52:13.883348Z","caller":"traceutil/trace.go:171","msg":"trace[645197358] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1489; }","duration":"122.907446ms","start":"2026-04-11T17:52:13.760428Z","end":"2026-04-11T17:52:13.883335Z","steps":["trace[645197358] 'agreement among raft nodes before linearized reading' (duration: 122.734792ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:13.883322Z","caller":"traceutil/trace.go:171","msg":"trace[393394] transaction","detail":"{read_only:false; response_revision:1489; number_of_response:1; }","duration":"125.709772ms","start":"2026-04-11T17:52:13.757581Z","end":"2026-04-11T17:52:13.883291Z","steps":["trace[393394] 'process raft request' (duration: 123.588175ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:20.017894Z","caller":"traceutil/trace.go:171","msg":"trace[1978527652] transaction","detail":"{read_only:false; response_revision:1517; number_of_response:1; }","duration":"102.438825ms","start":"2026-04-11T17:52:19.915407Z","end":"2026-04-11T17:52:20.017846Z","steps":["trace[1978527652] 'process raft request' (duration: 77.817673ms)","trace[1978527652] 'compare' (duration: 24.342845ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:36.622087Z","caller":"traceutil/trace.go:171","msg":"trace[377441707] linearizableReadLoop","detail":"{readStateIndex:1661; appliedIndex:1658; }","duration":"401.851999ms","start":"2026-04-11T17:52:36.220189Z","end":"2026-04-11T17:52:36.622041Z","steps":["trace[377441707] 'read index received' (duration: 19.139566ms)","trace[377441707] 'applied index is now lower than readState.Index' (duration: 382.711193ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:36.622109Z","caller":"traceutil/trace.go:171","msg":"trace[1796976775] transaction","detail":"{read_only:false; response_revision:1590; number_of_response:1; }","duration":"424.279772ms","start":"2026-04-11T17:52:36.197791Z","end":"2026-04-11T17:52:36.622071Z","steps":["trace[1796976775] 'process raft request' (duration: 402.172147ms)","trace[1796976775] 'compare' (duration: 21.864268ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:52:36.622136Z","caller":"traceutil/trace.go:171","msg":"trace[861378104] transaction","detail":"{read_only:false; response_revision:1591; number_of_response:1; }","duration":"424.259101ms","start":"2026-04-11T17:52:36.197842Z","end":"2026-04-11T17:52:36.622101Z","steps":["trace[861378104] 'process raft request' (duration: 424.140638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:36.622276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:52:36.197764Z","time spent":"424.418805ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:52:36.622297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:52:36.19781Z","time spent":"424.397646ms","remote":"127.0.0.1:60726","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-11T17:52:36.622285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.072174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-11T17:52:36.622528Z","caller":"traceutil/trace.go:171","msg":"trace[594509659] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:1591; }","duration":"402.366553ms","start":"2026-04-11T17:52:36.220142Z","end":"2026-04-11T17:52:36.622509Z","steps":["trace[594509659] 'agreement among raft nodes before linearized reading' (duration: 402.073015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:52:36.622583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-11T17:52:36.220079Z","time spent":"402.485296ms","remote":"127.0.0.1:60210","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/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true "} {"level":"warn","ts":"2026-04-11T17:52:36.622532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.633472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-11T17:52:36.622771Z","caller":"traceutil/trace.go:171","msg":"trace[224005149] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1591; }","duration":"182.908789ms","start":"2026-04-11T17:52:36.439837Z","end":"2026-04-11T17:52:36.622746Z","steps":["trace[224005149] 'agreement among raft nodes before linearized reading' (duration: 182.526209ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:50.446445Z","caller":"traceutil/trace.go:171","msg":"trace[1211784364] transaction","detail":"{read_only:false; response_revision:1653; number_of_response:1; }","duration":"179.9169ms","start":"2026-04-11T17:52:50.26648Z","end":"2026-04-11T17:52:50.446397Z","steps":["trace[1211784364] 'process raft request' (duration: 165.586184ms)","trace[1211784364] 'compare' (duration: 13.933805ms)"],"step_count":2} {"level":"warn","ts":"2026-04-11T17:52:50.446674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.51108ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-11T17:52:50.446983Z","caller":"traceutil/trace.go:171","msg":"trace[1506137310] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1653; }","duration":"121.890859ms","start":"2026-04-11T17:52:50.325071Z","end":"2026-04-11T17:52:50.446961Z","steps":["trace[1506137310] 'agreement among raft nodes before linearized reading' (duration: 121.406696ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:52:50.446347Z","caller":"traceutil/trace.go:171","msg":"trace[824743589] linearizableReadLoop","detail":"{readStateIndex:1726; appliedIndex:1724; }","duration":"121.16829ms","start":"2026-04-11T17:52:50.325138Z","end":"2026-04-11T17:52:50.446306Z","steps":["trace[824743589] 'read index received' (duration: 14.787878ms)","trace[824743589] 'applied index is now lower than readState.Index' (duration: 106.377772ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:53:00.600371Z","caller":"traceutil/trace.go:171","msg":"trace[503322652] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1772; }","duration":"100.236741ms","start":"2026-04-11T17:53:00.500111Z","end":"2026-04-11T17:53:00.600348Z","steps":["trace[503322652] 'read index received' (duration: 100.168439ms)","trace[503322652] 'applied index is now lower than readState.Index' (duration: 66.832µs)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:53:00.600404Z","caller":"traceutil/trace.go:171","msg":"trace[1780691045] transaction","detail":"{read_only:false; response_revision:1698; number_of_response:1; }","duration":"103.919352ms","start":"2026-04-11T17:53:00.49645Z","end":"2026-04-11T17:53:00.600369Z","steps":["trace[1780691045] 'process raft request' (duration: 103.761378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-11T17:53:00.60057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.436126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-11T17:53:00.600607Z","caller":"traceutil/trace.go:171","msg":"trace[1106680081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1698; }","duration":"100.529238ms","start":"2026-04-11T17:53:00.500068Z","end":"2026-04-11T17:53:00.600597Z","steps":["trace[1106680081] 'agreement among raft nodes before linearized reading' (duration: 100.372944ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:53:00.615008Z","caller":"traceutil/trace.go:171","msg":"trace[660662497] transaction","detail":"{read_only:false; response_revision:1699; number_of_response:1; }","duration":"114.667587ms","start":"2026-04-11T17:53:00.500328Z","end":"2026-04-11T17:53:00.614995Z","steps":["trace[660662497] 'process raft request' (duration: 114.596994ms)"],"step_count":1} {"level":"info","ts":"2026-04-11T17:54:59.795665Z","caller":"traceutil/trace.go:171","msg":"trace[2050841887] transaction","detail":"{read_only:false; response_revision:2271; number_of_response:1; }","duration":"172.96008ms","start":"2026-04-11T17:54:59.622645Z","end":"2026-04-11T17:54:59.795605Z","steps":["trace[2050841887] 'process raft request' (duration: 133.40262ms)","trace[2050841887] 'compare' (duration: 39.025026ms)"],"step_count":2} {"level":"info","ts":"2026-04-11T17:54:59.796613Z","caller":"traceutil/trace.go:171","msg":"trace[617819644] transaction","detail":"{read_only:false; response_revision:2272; number_of_response:1; }","duration":"172.721934ms","start":"2026-04-11T17:54:59.623071Z","end":"2026-04-11T17:54:59.795793Z","steps":["trace[617819644] 'process raft request' (duration: 172.56365ms)"],"step_count":1}