{"level":"warn","ts":"2026-02-10T15:03:02.826308Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:03:02.826421Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.191:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.191:2380","--initial-cluster=instance=https://199.19.213.191:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.191:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.191: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-02-10T15:03:02.826625Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T15:03:02.82664Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-02-10T15:03:02.82667Z","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-02-10T15:03:02.829174Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"]} {"level":"info","ts":"2026-02-10T15:03:02.829313Z","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":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.191: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-02-10T15:03:02.833802Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.208269ms"} {"level":"info","ts":"2026-02-10T15:03:02.842349Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e892d50a3a4fc0f4","cluster-id":"21eccdda20ff4e92"} {"level":"info","ts":"2026-02-10T15:03:02.842399Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T15:03:02.842497Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 0"} {"level":"info","ts":"2026-02-10T15:03:02.842565Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e892d50a3a4fc0f4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T15:03:02.842606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became follower at term 1"} {"level":"info","ts":"2026-02-10T15:03:02.842684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"warn","ts":"2026-02-10T15:03:02.848283Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T15:03:02.875851Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T15:03:02.883152Z","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-02-10T15:03:02.895805Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e892d50a3a4fc0f4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T15:03:02.898147Z","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-02-10T15:03:02.898359Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e892d50a3a4fc0f4","initial-advertise-peer-urls":["https://199.19.213.191:2380"],"listen-peer-urls":["https://199.19.213.191:2380"],"advertise-client-urls":["https://199.19.213.191:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.191:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T15:03:02.898431Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T15:03:02.903544Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e892d50a3a4fc0f4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T15:03:02.903727Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-02-10T15:03:02.903742Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.191:2380"} {"level":"info","ts":"2026-02-10T15:03:02.904993Z","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-02-10T15:03:02.905033Z","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-02-10T15:03:02.905041Z","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-02-10T15:03:02.911052Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 switched to configuration voters=(16758691403303731444)"} {"level":"info","ts":"2026-02-10T15:03:02.911173Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","added-peer-id":"e892d50a3a4fc0f4","added-peer-peer-urls":["https://199.19.213.191:2380"]} {"level":"info","ts":"2026-02-10T15:03:03.542948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T15:03:03.54303Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T15:03:03.543057Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgPreVoteResp from e892d50a3a4fc0f4 at term 1"} {"level":"info","ts":"2026-02-10T15:03:03.543089Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became candidate at term 2"} {"level":"info","ts":"2026-02-10T15:03:03.543097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 received MsgVoteResp from e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-02-10T15:03:03.543106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e892d50a3a4fc0f4 became leader at term 2"} {"level":"info","ts":"2026-02-10T15:03:03.543113Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e892d50a3a4fc0f4 elected leader e892d50a3a4fc0f4 at term 2"} {"level":"info","ts":"2026-02-10T15:03:03.545171Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:03:03.547035Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e892d50a3a4fc0f4","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.191:2379]}","request-path":"/0/members/e892d50a3a4fc0f4/attributes","cluster-id":"21eccdda20ff4e92","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T15:03:03.551067Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:03:03.551158Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T15:03:03.551389Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"21eccdda20ff4e92","local-member-id":"e892d50a3a4fc0f4","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:03:03.551516Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:03:03.551591Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T15:03:03.551676Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T15:03:03.551694Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T15:03:03.553126Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.191:2379"} {"level":"info","ts":"2026-02-10T15:03:03.562326Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T15:03:05.406446Z","caller":"traceutil/trace.go:171","msg":"trace[1214038305] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"229.780357ms","start":"2026-02-10T15:03:05.17665Z","end":"2026-02-10T15:03:05.40643Z","steps":["trace[1214038305] 'process raft request' (duration: 229.396428ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.406765Z","caller":"traceutil/trace.go:171","msg":"trace[1734243593] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"225.610168ms","start":"2026-02-10T15:03:05.181146Z","end":"2026-02-10T15:03:05.406756Z","steps":["trace[1734243593] 'process raft request' (duration: 225.078446ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.406977Z","caller":"traceutil/trace.go:171","msg":"trace[1237610806] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"225.745303ms","start":"2026-02-10T15:03:05.181221Z","end":"2026-02-10T15:03:05.406966Z","steps":["trace[1237610806] 'process raft request' (duration: 225.030666ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.407124Z","caller":"traceutil/trace.go:171","msg":"trace[1130264057] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"225.871616ms","start":"2026-02-10T15:03:05.181244Z","end":"2026-02-10T15:03:05.407116Z","steps":["trace[1130264057] 'process raft request' (duration: 225.032985ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.407244Z","caller":"traceutil/trace.go:171","msg":"trace[1598857538] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"225.969658ms","start":"2026-02-10T15:03:05.181267Z","end":"2026-02-10T15:03:05.407236Z","steps":["trace[1598857538] 'process raft request' (duration: 225.042006ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.407343Z","caller":"traceutil/trace.go:171","msg":"trace[505814069] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"225.859096ms","start":"2026-02-10T15:03:05.181477Z","end":"2026-02-10T15:03:05.407336Z","steps":["trace[505814069] 'process raft request' (duration: 224.855782ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.407597Z","caller":"traceutil/trace.go:171","msg":"trace[1394426603] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"230.764941ms","start":"2026-02-10T15:03:05.176799Z","end":"2026-02-10T15:03:05.407564Z","steps":["trace[1394426603] 'process raft request' (duration: 229.367098ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.406466Z","caller":"traceutil/trace.go:171","msg":"trace[1307607065] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:5; }","duration":"172.702567ms","start":"2026-02-10T15:03:05.23368Z","end":"2026-02-10T15:03:05.406383Z","steps":["trace[1307607065] 'read index received' (duration: 172.39699ms)","trace[1307607065] 'applied index is now lower than readState.Index' (duration: 304.777µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:05.407493Z","caller":"traceutil/trace.go:171","msg":"trace[1627872058] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"207.964436ms","start":"2026-02-10T15:03:05.199519Z","end":"2026-02-10T15:03:05.407484Z","steps":["trace[1627872058] 'process raft request' (duration: 206.838189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.406597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.953501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.410488Z","caller":"traceutil/trace.go:171","msg":"trace[1245461264] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"233.866013ms","start":"2026-02-10T15:03:05.176612Z","end":"2026-02-10T15:03:05.410478Z","steps":["trace[1245461264] 'agreement among raft nodes before linearized reading' (duration: 229.90552ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.410751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.903481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.410841Z","caller":"traceutil/trace.go:171","msg":"trace[367805315] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"163.993623ms","start":"2026-02-10T15:03:05.246838Z","end":"2026-02-10T15:03:05.410832Z","steps":["trace[367805315] 'agreement among raft nodes before linearized reading' (duration: 163.8792ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.40665Z","caller":"traceutil/trace.go:171","msg":"trace[1693937420] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"227.054294ms","start":"2026-02-10T15:03:05.179587Z","end":"2026-02-10T15:03:05.406641Z","steps":["trace[1693937420] 'process raft request' (duration: 226.611173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.409508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.331029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.411198Z","caller":"traceutil/trace.go:171","msg":"trace[43657380] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"183.04167ms","start":"2026-02-10T15:03:05.228148Z","end":"2026-02-10T15:03:05.41119Z","steps":["trace[43657380] 'agreement among raft nodes before linearized reading' (duration: 181.323459ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.409575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.88885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.411312Z","caller":"traceutil/trace.go:171","msg":"trace[556307766] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11; }","duration":"234.632562ms","start":"2026-02-10T15:03:05.176674Z","end":"2026-02-10T15:03:05.411307Z","steps":["trace[556307766] 'agreement among raft nodes before linearized reading' (duration: 232.88692ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.409547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.3875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.411588Z","caller":"traceutil/trace.go:171","msg":"trace[31265627] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"183.442769ms","start":"2026-02-10T15:03:05.228137Z","end":"2026-02-10T15:03:05.411579Z","steps":["trace[31265627] 'agreement among raft nodes before linearized reading' (duration: 181.394741ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.606088Z","caller":"traceutil/trace.go:171","msg":"trace[305575711] linearizableReadLoop","detail":"{readStateIndex:15; appliedIndex:14; }","duration":"185.090928ms","start":"2026-02-10T15:03:05.420972Z","end":"2026-02-10T15:03:05.606063Z","steps":["trace[305575711] 'read index received' (duration: 114.694984ms)","trace[305575711] 'applied index is now lower than readState.Index' (duration: 70.394704ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:05.606407Z","caller":"traceutil/trace.go:171","msg":"trace[292048596] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"185.456496ms","start":"2026-02-10T15:03:05.420932Z","end":"2026-02-10T15:03:05.606389Z","steps":["trace[292048596] 'process raft request' (duration: 114.727134ms)","trace[292048596] 'compare' (duration: 70.270341ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:05.606744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.751423ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:2859"} {"level":"info","ts":"2026-02-10T15:03:05.606791Z","caller":"traceutil/trace.go:171","msg":"trace[994167749] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:12; }","duration":"185.819674ms","start":"2026-02-10T15:03:05.420957Z","end":"2026-02-10T15:03:05.606776Z","steps":["trace[994167749] 'agreement among raft nodes before linearized reading' (duration: 185.65954ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.607761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.562841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-vlgj8\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-02-10T15:03:05.607829Z","caller":"traceutil/trace.go:171","msg":"trace[1930722179] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-vlgj8; range_end:; response_count:1; response_revision:19; }","duration":"186.613413ms","start":"2026-02-10T15:03:05.421183Z","end":"2026-02-10T15:03:05.607797Z","steps":["trace[1930722179] 'agreement among raft nodes before linearized reading' (duration: 186.48413ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608102Z","caller":"traceutil/trace.go:171","msg":"trace[74323280] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"187.086463ms","start":"2026-02-10T15:03:05.421001Z","end":"2026-02-10T15:03:05.608087Z","steps":["trace[74323280] 'process raft request' (duration: 186.331606ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608262Z","caller":"traceutil/trace.go:171","msg":"trace[1757601351] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"187.201086ms","start":"2026-02-10T15:03:05.421048Z","end":"2026-02-10T15:03:05.608249Z","steps":["trace[1757601351] 'process raft request' (duration: 186.345526ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608424Z","caller":"traceutil/trace.go:171","msg":"trace[1298331264] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"187.33926ms","start":"2026-02-10T15:03:05.421072Z","end":"2026-02-10T15:03:05.608411Z","steps":["trace[1298331264] 'process raft request' (duration: 186.365957ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608599Z","caller":"traceutil/trace.go:171","msg":"trace[123353133] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"187.490723ms","start":"2026-02-10T15:03:05.421091Z","end":"2026-02-10T15:03:05.608582Z","steps":["trace[123353133] 'process raft request' (duration: 186.381677ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608777Z","caller":"traceutil/trace.go:171","msg":"trace[710798849] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"187.635787ms","start":"2026-02-10T15:03:05.421128Z","end":"2026-02-10T15:03:05.608764Z","steps":["trace[710798849] 'process raft request' (duration: 186.375347ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.608993Z","caller":"traceutil/trace.go:171","msg":"trace[1191295871] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"187.822732ms","start":"2026-02-10T15:03:05.421158Z","end":"2026-02-10T15:03:05.608981Z","steps":["trace[1191295871] 'process raft request' (duration: 186.376688ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.60915Z","caller":"traceutil/trace.go:171","msg":"trace[1266506787] transaction","detail":"{read_only:false; number_of_response:0; response_revision:18; }","duration":"187.931804ms","start":"2026-02-10T15:03:05.421207Z","end":"2026-02-10T15:03:05.609139Z","steps":["trace[1266506787] 'process raft request' (duration: 186.362816ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.609415Z","caller":"traceutil/trace.go:171","msg":"trace[1313292371] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"186.626874ms","start":"2026-02-10T15:03:05.422775Z","end":"2026-02-10T15:03:05.609402Z","steps":["trace[1313292371] 'process raft request' (duration: 184.824801ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.609675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.538847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-02-10T15:03:05.609722Z","caller":"traceutil/trace.go:171","msg":"trace[974500992] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"133.593208ms","start":"2026-02-10T15:03:05.476111Z","end":"2026-02-10T15:03:05.609704Z","steps":["trace[974500992] 'agreement among raft nodes before linearized reading' (duration: 133.525416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.609896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.812687ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.609937Z","caller":"traceutil/trace.go:171","msg":"trace[1221090013] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:19; }","duration":"140.888969ms","start":"2026-02-10T15:03:05.469035Z","end":"2026-02-10T15:03:05.609924Z","steps":["trace[1221090013] 'agreement among raft nodes before linearized reading' (duration: 140.829827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.886283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.600544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.886364Z","caller":"traceutil/trace.go:171","msg":"trace[310783564] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:29; }","duration":"258.708618ms","start":"2026-02-10T15:03:05.627634Z","end":"2026-02-10T15:03:05.886343Z","steps":["trace[310783564] 'agreement among raft nodes before linearized reading' (duration: 79.398396ms)","trace[310783564] 'range keys from in-memory index tree' (duration: 179.179599ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:05.887326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.298253ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-02-10T15:03:05.887962Z","caller":"traceutil/trace.go:171","msg":"trace[846042570] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"177.176892ms","start":"2026-02-10T15:03:05.710768Z","end":"2026-02-10T15:03:05.887945Z","steps":["trace[846042570] 'process raft request' (duration: 177.139881ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.888492Z","caller":"traceutil/trace.go:171","msg":"trace[1502128974] transaction","detail":"{read_only:false; response_revision:30; number_of_response:1; }","duration":"206.579752ms","start":"2026-02-10T15:03:05.681863Z","end":"2026-02-10T15:03:05.888443Z","steps":["trace[1502128974] 'process raft request' (duration: 36.111299ms)","trace[1502128974] 'compare' (duration: 168.17474ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:05.888876Z","caller":"traceutil/trace.go:171","msg":"trace[555498799] linearizableReadLoop","detail":"{readStateIndex:38; appliedIndex:33; }","duration":"182.536688ms","start":"2026-02-10T15:03:05.706322Z","end":"2026-02-10T15:03:05.888859Z","steps":["trace[555498799] 'read index received' (duration: 11.659504ms)","trace[555498799] 'applied index is now lower than readState.Index' (duration: 170.875594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:05.889017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.017323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.889051Z","caller":"traceutil/trace.go:171","msg":"trace[114393763] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:35; }","duration":"207.070104ms","start":"2026-02-10T15:03:05.681972Z","end":"2026-02-10T15:03:05.889042Z","steps":["trace[114393763] 'agreement among raft nodes before linearized reading' (duration: 206.945101ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.88919Z","caller":"traceutil/trace.go:171","msg":"trace[38325671] transaction","detail":"{read_only:false; response_revision:31; number_of_response:1; }","duration":"205.585109ms","start":"2026-02-10T15:03:05.683595Z","end":"2026-02-10T15:03:05.88918Z","steps":["trace[38325671] 'process raft request' (duration: 204.171726ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.88931Z","caller":"traceutil/trace.go:171","msg":"trace[308786767] transaction","detail":"{read_only:false; response_revision:32; number_of_response:1; }","duration":"205.642151ms","start":"2026-02-10T15:03:05.683656Z","end":"2026-02-10T15:03:05.889298Z","steps":["trace[308786767] 'process raft request' (duration: 204.164966ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.889952Z","caller":"traceutil/trace.go:171","msg":"trace[2008797272] transaction","detail":"{read_only:false; response_revision:33; number_of_response:1; }","duration":"206.247765ms","start":"2026-02-10T15:03:05.683681Z","end":"2026-02-10T15:03:05.889929Z","steps":["trace[2008797272] 'process raft request' (duration: 204.174996ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:05.890261Z","caller":"traceutil/trace.go:171","msg":"trace[1707341583] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"206.548882ms","start":"2026-02-10T15:03:05.683701Z","end":"2026-02-10T15:03:05.89025Z","steps":["trace[1707341583] 'process raft request' (duration: 204.178066ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:05.890505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.084255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:05.890546Z","caller":"traceutil/trace.go:171","msg":"trace[411117500] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:35; }","duration":"207.133066ms","start":"2026-02-10T15:03:05.6834Z","end":"2026-02-10T15:03:05.890533Z","steps":["trace[411117500] 'agreement among raft nodes before linearized reading' (duration: 207.074374ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:06.209312Z","caller":"traceutil/trace.go:171","msg":"trace[1602514772] linearizableReadLoop","detail":"{readStateIndex:45; appliedIndex:43; }","duration":"203.981732ms","start":"2026-02-10T15:03:06.005306Z","end":"2026-02-10T15:03:06.209288Z","steps":["trace[1602514772] 'read index received' (duration: 123.348227ms)","trace[1602514772] 'applied index is now lower than readState.Index' (duration: 80.632565ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:06.209713Z","caller":"traceutil/trace.go:171","msg":"trace[925883536] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"205.366375ms","start":"2026-02-10T15:03:06.004162Z","end":"2026-02-10T15:03:06.209529Z","steps":["trace[925883536] 'process raft request' (duration: 205.059698ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:06.212015Z","caller":"traceutil/trace.go:171","msg":"trace[391848380] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"206.329467ms","start":"2026-02-10T15:03:06.003149Z","end":"2026-02-10T15:03:06.209479Z","steps":["trace[391848380] 'process raft request' (duration: 125.490858ms)","trace[391848380] 'compare' (duration: 80.335758ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:06.214969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.405146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:06.215088Z","caller":"traceutil/trace.go:171","msg":"trace[1799043972] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:41; }","duration":"209.790729ms","start":"2026-02-10T15:03:06.00527Z","end":"2026-02-10T15:03:06.215061Z","steps":["trace[1799043972] 'agreement among raft nodes before linearized reading' (duration: 208.14955ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:06.215861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.567265ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/default\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:06.215892Z","caller":"traceutil/trace.go:171","msg":"trace[1633583084] range","detail":"{range_begin:/registry/namespaces/default; range_end:; response_count:0; response_revision:41; }","duration":"161.668818ms","start":"2026-02-10T15:03:06.054212Z","end":"2026-02-10T15:03:06.215881Z","steps":["trace[1633583084] 'agreement among raft nodes before linearized reading' (duration: 161.575965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:06.216105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.4458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:06.216125Z","caller":"traceutil/trace.go:171","msg":"trace[1977217028] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:41; }","duration":"146.516951ms","start":"2026-02-10T15:03:06.069601Z","end":"2026-02-10T15:03:06.216118Z","steps":["trace[1977217028] 'agreement among raft nodes before linearized reading' (duration: 146.48579ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:06.216234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.86415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-10T15:03:06.216251Z","caller":"traceutil/trace.go:171","msg":"trace[1950312025] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:41; }","duration":"146.936201ms","start":"2026-02-10T15:03:06.069309Z","end":"2026-02-10T15:03:06.216246Z","steps":["trace[1950312025] 'agreement among raft nodes before linearized reading' (duration: 146.89926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.107927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.863865ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:03:13.10805Z","caller":"traceutil/trace.go:171","msg":"trace[1440369258] linearizableReadLoop","detail":"{readStateIndex:353; appliedIndex:352; }","duration":"642.672876ms","start":"2026-02-10T15:03:12.465362Z","end":"2026-02-10T15:03:13.108035Z","steps":["trace[1440369258] 'read index received' (duration: 247.36969ms)","trace[1440369258] 'applied index is now lower than readState.Index' (duration: 395.302106ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:13.10813Z","caller":"traceutil/trace.go:171","msg":"trace[951546844] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"656.175294ms","start":"2026-02-10T15:03:12.451945Z","end":"2026-02-10T15:03:13.108121Z","steps":["trace[951546844] 'process raft request' (duration: 260.902118ms)","trace[951546844] 'compare' (duration: 394.739842ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:13.108184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.451926Z","time spent":"656.229465ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:13.108457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"643.088756ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-02-10T15:03:13.108508Z","caller":"traceutil/trace.go:171","msg":"trace[756972564] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:344; }","duration":"643.150527ms","start":"2026-02-10T15:03:12.465332Z","end":"2026-02-10T15:03:13.108482Z","steps":["trace[756972564] 'agreement among raft nodes before linearized reading' (duration: 643.082765ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.10858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.465317Z","time spent":"643.253919ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":226,"request content":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" "} {"level":"warn","ts":"2026-02-10T15:03:13.10874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"592.271632ms","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-02-10T15:03:13.108777Z","caller":"traceutil/trace.go:171","msg":"trace[379055117] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:344; }","duration":"592.306673ms","start":"2026-02-10T15:03:12.516459Z","end":"2026-02-10T15:03:13.108766Z","steps":["trace[379055117] 'agreement among raft nodes before linearized reading' (duration: 592.252151ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.108796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.840467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-02-10T15:03:13.108883Z","caller":"traceutil/trace.go:171","msg":"trace[1856308251] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:344; }","duration":"343.95985ms","start":"2026-02-10T15:03:12.764908Z","end":"2026-02-10T15:03:13.108867Z","steps":["trace[1856308251] 'agreement among raft nodes before linearized reading' (duration: 343.827557ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.108884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.516397Z","time spent":"592.407116ms","remote":"127.0.0.1:44046","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":"warn","ts":"2026-02-10T15:03:13.108908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.764892Z","time spent":"344.010831ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" "} {"level":"warn","ts":"2026-02-10T15:03:13.10902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.329782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"info","ts":"2026-02-10T15:03:13.10905Z","caller":"traceutil/trace.go:171","msg":"trace[271076077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:344; }","duration":"394.397044ms","start":"2026-02-10T15:03:12.714643Z","end":"2026-02-10T15:03:13.10904Z","steps":["trace[271076077] 'agreement among raft nodes before linearized reading' (duration: 394.341903ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.10908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.397471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"warn","ts":"2026-02-10T15:03:13.109079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.714627Z","time spent":"394.445045ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":1,"response size":232,"request content":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" "} {"level":"info","ts":"2026-02-10T15:03:13.109097Z","caller":"traceutil/trace.go:171","msg":"trace[1209571323] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:344; }","duration":"244.509403ms","start":"2026-02-10T15:03:12.864583Z","end":"2026-02-10T15:03:13.109092Z","steps":["trace[1209571323] 'agreement among raft nodes before linearized reading' (duration: 244.478962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.109148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.797107ms","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-02-10T15:03:13.10916Z","caller":"traceutil/trace.go:171","msg":"trace[1321202710] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:344; }","duration":"292.852949ms","start":"2026-02-10T15:03:12.816304Z","end":"2026-02-10T15:03:13.109157Z","steps":["trace[1321202710] 'agreement among raft nodes before linearized reading' (duration: 292.831378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.109275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"438.445489ms","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-02-10T15:03:13.10931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"494.574787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" ","response":"range_response_count:1 size:197"} {"level":"info","ts":"2026-02-10T15:03:13.109315Z","caller":"traceutil/trace.go:171","msg":"trace[1391483528] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-account-controller; range_end:; response_count:1; response_revision:344; }","duration":"438.509871ms","start":"2026-02-10T15:03:12.670794Z","end":"2026-02-10T15:03:13.109304Z","steps":["trace[1391483528] 'agreement among raft nodes before linearized reading' (duration: 438.442219ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.109354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.171792ms","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-02-10T15:03:13.109351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.67078Z","time spent":"438.564632ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":1,"response size":241,"request content":"key:\"/registry/serviceaccounts/kube-system/service-account-controller\" "} {"level":"info","ts":"2026-02-10T15:03:13.109369Z","caller":"traceutil/trace.go:171","msg":"trace[2009133133] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/root-ca-cert-publisher; range_end:; response_count:1; response_revision:344; }","duration":"544.217252ms","start":"2026-02-10T15:03:12.565148Z","end":"2026-02-10T15:03:13.109365Z","steps":["trace[2009133133] 'agreement among raft nodes before linearized reading' (duration: 544.189572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.109382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.565111Z","time spent":"544.268424ms","remote":"127.0.0.1:44046","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-02-10T15:03:13.109328Z","caller":"traceutil/trace.go:171","msg":"trace[1128350878] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/bootstrap-signer; range_end:; response_count:1; response_revision:344; }","duration":"494.617378ms","start":"2026-02-10T15:03:12.614705Z","end":"2026-02-10T15:03:13.109322Z","steps":["trace[1128350878] 'agreement among raft nodes before linearized reading' (duration: 494.589558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.10948Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.614692Z","time spent":"494.782522ms","remote":"127.0.0.1:44046","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":220,"request content":"key:\"/registry/serviceaccounts/kube-system/bootstrap-signer\" "} {"level":"warn","ts":"2026-02-10T15:03:13.109512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.270355ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-02-10T15:03:13.109532Z","caller":"traceutil/trace.go:171","msg":"trace[52638739] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:1; response_revision:344; }","duration":"590.302825ms","start":"2026-02-10T15:03:12.519223Z","end":"2026-02-10T15:03:13.109526Z","steps":["trace[52638739] 'agreement among raft nodes before linearized reading' (duration: 590.253664ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:13.109548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:12.519216Z","time spent":"590.328827ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":558,"request content":"key:\"/registry/leases/kube-node-lease/instance\" "} {"level":"info","ts":"2026-02-10T15:03:13.616937Z","caller":"traceutil/trace.go:171","msg":"trace[55788714] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"191.769814ms","start":"2026-02-10T15:03:13.425131Z","end":"2026-02-10T15:03:13.6169Z","steps":["trace[55788714] 'process raft request' (duration: 94.236583ms)","trace[55788714] 'compare' (duration: 97.408298ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:22.666009Z","caller":"traceutil/trace.go:171","msg":"trace[577826585] transaction","detail":"{read_only:false; response_revision:423; number_of_response:1; }","duration":"136.798252ms","start":"2026-02-10T15:03:22.529041Z","end":"2026-02-10T15:03:22.665839Z","steps":["trace[577826585] 'process raft request' (duration: 103.49095ms)","trace[577826585] 'compare' (duration: 33.157948ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:22.671155Z","caller":"traceutil/trace.go:171","msg":"trace[1547447771] linearizableReadLoop","detail":"{readStateIndex:436; appliedIndex:434; }","duration":"133.435453ms","start":"2026-02-10T15:03:22.537709Z","end":"2026-02-10T15:03:22.671144Z","steps":["trace[1547447771] 'read index received' (duration: 94.833347ms)","trace[1547447771] 'applied index is now lower than readState.Index' (duration: 38.601606ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:22.671373Z","caller":"traceutil/trace.go:171","msg":"trace[1805976153] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"133.739231ms","start":"2026-02-10T15:03:22.537627Z","end":"2026-02-10T15:03:22.671366Z","steps":["trace[1805976153] 'process raft request' (duration: 133.454494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:22.671512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.794172ms","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-02-10T15:03:22.671556Z","caller":"traceutil/trace.go:171","msg":"trace[1832726499] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:424; }","duration":"133.867453ms","start":"2026-02-10T15:03:22.537681Z","end":"2026-02-10T15:03:22.671548Z","steps":["trace[1832726499] 'agreement among raft nodes before linearized reading' (duration: 133.801911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:22.671658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.180427ms","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-02-10T15:03:22.67262Z","caller":"traceutil/trace.go:171","msg":"trace[1481515157] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:424; }","duration":"134.14111ms","start":"2026-02-10T15:03:22.538472Z","end":"2026-02-10T15:03:22.672614Z","steps":["trace[1481515157] 'agreement among raft nodes before linearized reading' (duration: 133.169067ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:29.247587Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983261676,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:03:29.310998Z","caller":"traceutil/trace.go:171","msg":"trace[2091192648] linearizableReadLoop","detail":"{readStateIndex:477; appliedIndex:476; }","duration":"564.293295ms","start":"2026-02-10T15:03:28.746682Z","end":"2026-02-10T15:03:29.310976Z","steps":["trace[2091192648] 'read index received' (duration: 564.074229ms)","trace[2091192648] 'applied index is now lower than readState.Index' (duration: 218.186µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:29.31114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.437829ms","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-02-10T15:03:29.311164Z","caller":"traceutil/trace.go:171","msg":"trace[1501310812] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:463; }","duration":"564.50492ms","start":"2026-02-10T15:03:28.746651Z","end":"2026-02-10T15:03:29.311156Z","steps":["trace[1501310812] 'agreement among raft nodes before linearized reading' (duration: 564.438498ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:29.31119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:28.746637Z","time spent":"564.546231ms","remote":"127.0.0.1:44096","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-02-10T15:03:29.311225Z","caller":"traceutil/trace.go:171","msg":"trace[1599820513] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"588.75456ms","start":"2026-02-10T15:03:28.722447Z","end":"2026-02-10T15:03:29.311201Z","steps":["trace[1599820513] 'process raft request' (duration: 588.38398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:29.311337Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:28.722435Z","time spent":"588.834871ms","remote":"127.0.0.1:44096","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-02-10T15:03:34.044671Z","caller":"traceutil/trace.go:171","msg":"trace[1251232164] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"125.279763ms","start":"2026-02-10T15:03:33.919376Z","end":"2026-02-10T15:03:34.044656Z","steps":["trace[1251232164] 'read index received' (duration: 125.16061ms)","trace[1251232164] 'applied index is now lower than readState.Index' (duration: 118.653µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:34.044806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.419196ms","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-02-10T15:03:34.044827Z","caller":"traceutil/trace.go:171","msg":"trace[134390616] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:474; }","duration":"125.482876ms","start":"2026-02-10T15:03:33.919339Z","end":"2026-02-10T15:03:34.044822Z","steps":["trace[134390616] 'agreement among raft nodes before linearized reading' (duration: 125.377664ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:34.04503Z","caller":"traceutil/trace.go:171","msg":"trace[1894999393] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"151.99618ms","start":"2026-02-10T15:03:33.893028Z","end":"2026-02-10T15:03:34.045024Z","steps":["trace[1894999393] 'process raft request' (duration: 151.55353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:37.40009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.651735ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4062"} {"level":"info","ts":"2026-02-10T15:03:37.400196Z","caller":"traceutil/trace.go:171","msg":"trace[1638049733] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:515; }","duration":"107.81598ms","start":"2026-02-10T15:03:37.29236Z","end":"2026-02-10T15:03:37.400176Z","steps":["trace[1638049733] 'range keys from in-memory index tree' (duration: 107.565933ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:37.761374Z","caller":"traceutil/trace.go:171","msg":"trace[2136701399] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"355.415372ms","start":"2026-02-10T15:03:37.405941Z","end":"2026-02-10T15:03:37.761356Z","steps":["trace[2136701399] 'process raft request' (duration: 355.300968ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:37.761481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:37.405918Z","time spent":"355.507004ms","remote":"127.0.0.1:44096","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-02-10T15:03:37.765875Z","caller":"traceutil/trace.go:171","msg":"trace[441774373] linearizableReadLoop","detail":"{readStateIndex:533; appliedIndex:532; }","duration":"338.01707ms","start":"2026-02-10T15:03:37.427851Z","end":"2026-02-10T15:03:37.765868Z","steps":["trace[441774373] 'read index received' (duration: 333.914039ms)","trace[441774373] 'applied index is now lower than readState.Index' (duration: 4.102711ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:37.765982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.127023ms","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-02-10T15:03:37.765999Z","caller":"traceutil/trace.go:171","msg":"trace[916976582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:517; }","duration":"338.175724ms","start":"2026-02-10T15:03:37.427818Z","end":"2026-02-10T15:03:37.765993Z","steps":["trace[916976582] 'agreement among raft nodes before linearized reading' (duration: 338.084272ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:37.766013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:37.427804Z","time spent":"338.205776ms","remote":"127.0.0.1:44096","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-02-10T15:03:37.766154Z","caller":"traceutil/trace.go:171","msg":"trace[686743275] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"356.038129ms","start":"2026-02-10T15:03:37.410111Z","end":"2026-02-10T15:03:37.766149Z","steps":["trace[686743275] 'process raft request' (duration: 355.71965ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:37.766186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:37.410076Z","time spent":"356.08936ms","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:40.19344Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983261795,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:03:40.653268Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.210057396s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:03:40.653677Z","caller":"traceutil/trace.go:171","msg":"trace[206318408] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:536; }","duration":"960.872497ms","start":"2026-02-10T15:03:39.692783Z","end":"2026-02-10T15:03:40.653655Z","steps":["trace[206318408] 'read index received' (duration: 960.655151ms)","trace[206318408] 'applied index is now lower than readState.Index' (duration: 216.646µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:40.653962Z","caller":"traceutil/trace.go:171","msg":"trace[248969253] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"1.21085625s","start":"2026-02-10T15:03:39.443094Z","end":"2026-02-10T15:03:40.653951Z","steps":["trace[248969253] 'process raft request' (duration: 1.210393815s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.654064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:39.443073Z","time spent":"1.210926191s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:40.654249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.463045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:40.654286Z","caller":"traceutil/trace.go:171","msg":"trace[1950773001] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"961.523247ms","start":"2026-02-10T15:03:39.692753Z","end":"2026-02-10T15:03:40.654276Z","steps":["trace[1950773001] 'agreement among raft nodes before linearized reading' (duration: 961.461385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.654418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.991453ms","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-02-10T15:03:40.655093Z","caller":"traceutil/trace.go:171","msg":"trace[1010337056] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:521; }","duration":"890.681564ms","start":"2026-02-10T15:03:39.764397Z","end":"2026-02-10T15:03:40.655079Z","steps":["trace[1010337056] 'agreement among raft nodes before linearized reading' (duration: 889.942152ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.655122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:39.764385Z","time spent":"890.728265ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:03:40.654457Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.82427ms","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-02-10T15:03:40.655221Z","caller":"traceutil/trace.go:171","msg":"trace[139559534] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"565.603394ms","start":"2026-02-10T15:03:40.089613Z","end":"2026-02-10T15:03:40.655217Z","steps":["trace[139559534] 'agreement among raft nodes before linearized reading' (duration: 564.821741ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.655237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.089606Z","time spent":"565.625704ms","remote":"127.0.0.1:44096","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-02-10T15:03:40.654491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.977824ms","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-02-10T15:03:40.655306Z","caller":"traceutil/trace.go:171","msg":"trace[1487264924] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:521; }","duration":"567.822459ms","start":"2026-02-10T15:03:40.08748Z","end":"2026-02-10T15:03:40.655302Z","steps":["trace[1487264924] 'agreement among raft nodes before linearized reading' (duration: 566.992545ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.655321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.087464Z","time spent":"567.852589ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T15:03:40.654546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"866.009458ms","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-02-10T15:03:40.655388Z","caller":"traceutil/trace.go:171","msg":"trace[1488012561] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"866.873122ms","start":"2026-02-10T15:03:39.78851Z","end":"2026-02-10T15:03:40.655383Z","steps":["trace[1488012561] 'agreement among raft nodes before linearized reading' (duration: 866.016667ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:40.65541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:39.788498Z","time spent":"866.908414ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:03:40.654661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:39.692738Z","time spent":"961.888207ms","remote":"127.0.0.1:43842","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-02-10T15:03:41.08757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.045409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:41.087638Z","caller":"traceutil/trace.go:171","msg":"trace[1287940858] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:521; }","duration":"426.155852ms","start":"2026-02-10T15:03:40.661466Z","end":"2026-02-10T15:03:41.087621Z","steps":["trace[1287940858] 'range keys from in-memory index tree' (duration: 425.993277ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:41.087677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.661457Z","time spent":"426.211284ms","remote":"127.0.0.1:43844","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-02-10T15:03:41.088931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.918387ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:03:41.089003Z","caller":"traceutil/trace.go:171","msg":"trace[418769603] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"424.798623ms","start":"2026-02-10T15:03:40.664191Z","end":"2026-02-10T15:03:41.08899Z","steps":["trace[418769603] 'process raft request' (duration: 305.733701ms)","trace[418769603] 'compare' (duration: 118.273339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:41.089057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.664181Z","time spent":"424.851744ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:03:41.12145Z","caller":"traceutil/trace.go:171","msg":"trace[2121644017] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"455.70551ms","start":"2026-02-10T15:03:40.665719Z","end":"2026-02-10T15:03:41.121424Z","steps":["trace[2121644017] 'process raft request' (duration: 455.222216ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:41.121637Z","caller":"traceutil/trace.go:171","msg":"trace[1140357499] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"454.587808ms","start":"2026-02-10T15:03:40.667026Z","end":"2026-02-10T15:03:41.121614Z","steps":["trace[1140357499] 'process raft request' (duration: 453.952369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:41.121775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.66702Z","time spent":"454.687762ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:41.122343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.665712Z","time spent":"455.882836ms","remote":"127.0.0.1:44096","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-02-10T15:03:41.122847Z","caller":"traceutil/trace.go:171","msg":"trace[1247746528] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"458.541775ms","start":"2026-02-10T15:03:40.664289Z","end":"2026-02-10T15:03:41.12283Z","steps":["trace[1247746528] 'process raft request' (duration: 456.527475ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:41.123783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:40.664284Z","time spent":"459.453271ms","remote":"127.0.0.1:44096","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-02-10T15:03:42.124211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.011577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:42.124298Z","caller":"traceutil/trace.go:171","msg":"trace[192761514] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"427.12716ms","start":"2026-02-10T15:03:41.697146Z","end":"2026-02-10T15:03:42.124274Z","steps":["trace[192761514] 'range keys from in-memory index tree' (duration: 426.956425ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:42.124334Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:41.697134Z","time spent":"427.192562ms","remote":"127.0.0.1:43844","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-02-10T15:03:42.124511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:41.705737Z","time spent":"418.772794ms","remote":"127.0.0.1:56382","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-10T15:03:42.67707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.584558ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:03:45.390036Z","caller":"traceutil/trace.go:171","msg":"trace[1879584654] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"179.045236ms","start":"2026-02-10T15:03:45.210971Z","end":"2026-02-10T15:03:45.390016Z","steps":["trace[1879584654] 'process raft request' (duration: 178.907072ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:45.395266Z","caller":"traceutil/trace.go:171","msg":"trace[1019008954] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"182.912119ms","start":"2026-02-10T15:03:45.212346Z","end":"2026-02-10T15:03:45.395259Z","steps":["trace[1019008954] 'process raft request' (duration: 182.857108ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:45.395356Z","caller":"traceutil/trace.go:171","msg":"trace[847337401] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"181.890789ms","start":"2026-02-10T15:03:45.213443Z","end":"2026-02-10T15:03:45.395334Z","steps":["trace[847337401] 'process raft request' (duration: 181.797537ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:47.539366Z","caller":"traceutil/trace.go:171","msg":"trace[318509274] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"143.259603ms","start":"2026-02-10T15:03:47.396095Z","end":"2026-02-10T15:03:47.539355Z","steps":["trace[318509274] 'process raft request' (duration: 142.047918ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:03:47.539238Z","caller":"traceutil/trace.go:171","msg":"trace[882463077] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:555; }","duration":"140.496971ms","start":"2026-02-10T15:03:47.39872Z","end":"2026-02-10T15:03:47.539217Z","steps":["trace[882463077] 'read index received' (duration: 139.365748ms)","trace[882463077] 'applied index is now lower than readState.Index' (duration: 1.130293ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:47.539721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.984167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T15:03:47.539754Z","caller":"traceutil/trace.go:171","msg":"trace[1864320815] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:538; }","duration":"141.046808ms","start":"2026-02-10T15:03:47.398695Z","end":"2026-02-10T15:03:47.539742Z","steps":["trace[1864320815] 'agreement among raft nodes before linearized reading' (duration: 140.982027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:47.541646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.437619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:03:47.541689Z","caller":"traceutil/trace.go:171","msg":"trace[536298072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:538; }","duration":"141.517572ms","start":"2026-02-10T15:03:47.400161Z","end":"2026-02-10T15:03:47.541678Z","steps":["trace[536298072] 'agreement among raft nodes before linearized reading' (duration: 141.426299ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:49.009268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.453938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:49.009344Z","caller":"traceutil/trace.go:171","msg":"trace[736796167] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:542; }","duration":"312.573981ms","start":"2026-02-10T15:03:48.696757Z","end":"2026-02-10T15:03:49.009331Z","steps":["trace[736796167] 'range keys from in-memory index tree' (duration: 312.388486ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:49.009387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:48.696743Z","time spent":"312.637563ms","remote":"127.0.0.1:43844","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-02-10T15:03:49.009651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.755568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-wmggw\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T15:03:49.009676Z","caller":"traceutil/trace.go:171","msg":"trace[327043294] range","detail":"{range_begin:/registry/pods/kube-system/cilium-wmggw; range_end:; response_count:1; response_revision:542; }","duration":"683.810579ms","start":"2026-02-10T15:03:48.325858Z","end":"2026-02-10T15:03:49.009669Z","steps":["trace[327043294] 'range keys from in-memory index tree' (duration: 683.629014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:49.009695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:48.325841Z","time spent":"683.85009ms","remote":"127.0.0.1:44026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-wmggw\" "} {"level":"info","ts":"2026-02-10T15:03:49.637167Z","caller":"traceutil/trace.go:171","msg":"trace[194687787] linearizableReadLoop","detail":"{readStateIndex:561; appliedIndex:560; }","duration":"404.083743ms","start":"2026-02-10T15:03:49.233062Z","end":"2026-02-10T15:03:49.637146Z","steps":["trace[194687787] 'read index received' (duration: 403.894898ms)","trace[194687787] 'applied index is now lower than readState.Index' (duration: 187.895µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:03:49.637523Z","caller":"traceutil/trace.go:171","msg":"trace[2134895952] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"615.245323ms","start":"2026-02-10T15:03:49.022265Z","end":"2026-02-10T15:03:49.63751Z","steps":["trace[2134895952] 'process raft request' (duration: 614.748358ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:49.637618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.022249Z","time spent":"615.305904ms","remote":"127.0.0.1:44026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:03:49.637874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.802994ms","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-02-10T15:03:49.637918Z","caller":"traceutil/trace.go:171","msg":"trace[1776301493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:543; }","duration":"404.872997ms","start":"2026-02-10T15:03:49.233032Z","end":"2026-02-10T15:03:49.637905Z","steps":["trace[1776301493] 'agreement among raft nodes before linearized reading' (duration: 404.769093ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:49.637954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.233017Z","time spent":"404.927798ms","remote":"127.0.0.1:44096","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-02-10T15:03:50.191872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.46573ms","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-02-10T15:03:50.191991Z","caller":"traceutil/trace.go:171","msg":"trace[1275993057] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:543; }","duration":"411.595314ms","start":"2026-02-10T15:03:49.78038Z","end":"2026-02-10T15:03:50.191975Z","steps":["trace[1275993057] 'range keys from in-memory index tree' (duration: 411.45001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.192507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.387324ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:03:50.192562Z","caller":"traceutil/trace.go:171","msg":"trace[758137532] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"544.683287ms","start":"2026-02-10T15:03:49.647866Z","end":"2026-02-10T15:03:50.19255Z","steps":["trace[758137532] 'process raft request' (duration: 296.200351ms)","trace[758137532] 'compare' (duration: 248.157347ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:50.192609Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.647858Z","time spent":"544.727279ms","remote":"127.0.0.1:44096","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-02-10T15:03:50.193162Z","caller":"traceutil/trace.go:171","msg":"trace[1322359658] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:561; }","duration":"500.592911ms","start":"2026-02-10T15:03:49.692558Z","end":"2026-02-10T15:03:50.19315Z","steps":["trace[1322359658] 'read index received' (duration: 251.517276ms)","trace[1322359658] 'applied index is now lower than readState.Index' (duration: 249.074925ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:50.193232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.669513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:50.193256Z","caller":"traceutil/trace.go:171","msg":"trace[1962283721] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:547; }","duration":"500.695504ms","start":"2026-02-10T15:03:49.692552Z","end":"2026-02-10T15:03:50.193248Z","steps":["trace[1962283721] 'agreement among raft nodes before linearized reading' (duration: 500.641112ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.193297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.69251Z","time spent":"500.780636ms","remote":"127.0.0.1:43842","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-02-10T15:03:50.193501Z","caller":"traceutil/trace.go:171","msg":"trace[70863509] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"542.554235ms","start":"2026-02-10T15:03:49.650935Z","end":"2026-02-10T15:03:50.19349Z","steps":["trace[70863509] 'process raft request' (duration: 542.082721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.193559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.650923Z","time spent":"542.604897ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:03:50.193663Z","caller":"traceutil/trace.go:171","msg":"trace[534099864] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"542.713659ms","start":"2026-02-10T15:03:49.650941Z","end":"2026-02-10T15:03:50.193654Z","steps":["trace[534099864] 'process raft request' (duration: 542.149253ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.193709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.650932Z","time spent":"542.75102ms","remote":"127.0.0.1:44096","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-02-10T15:03:50.193836Z","caller":"traceutil/trace.go:171","msg":"trace[2142953769] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"542.830933ms","start":"2026-02-10T15:03:49.650996Z","end":"2026-02-10T15:03:50.193827Z","steps":["trace[2142953769] 'process raft request' (duration: 542.127473ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:50.193881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:49.650992Z","time spent":"542.865694ms","remote":"127.0.0.1:44096","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-02-10T15:03:51.440443Z","caller":"traceutil/trace.go:171","msg":"trace[1730186920] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"102.984089ms","start":"2026-02-10T15:03:51.337439Z","end":"2026-02-10T15:03:51.440423Z","steps":["trace[1730186920] 'process raft request' (duration: 102.846135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:53.234287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.561439ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:03:53.234395Z","caller":"traceutil/trace.go:171","msg":"trace[1723225072] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"635.773017ms","start":"2026-02-10T15:03:52.598603Z","end":"2026-02-10T15:03:53.234376Z","steps":["trace[1723225072] 'process raft request' (duration: 503.012043ms)","trace[1723225072] 'compare' (duration: 131.692102ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:53.23447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:52.598574Z","time spent":"635.853119ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5562,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:03:53.287197Z","caller":"traceutil/trace.go:171","msg":"trace[1721634865] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"688.031993ms","start":"2026-02-10T15:03:52.599141Z","end":"2026-02-10T15:03:53.287173Z","steps":["trace[1721634865] 'process raft request' (duration: 687.664853ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:53.287305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:52.599135Z","time spent":"688.117206ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":932,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:03:53.287822Z","caller":"traceutil/trace.go:171","msg":"trace[842843670] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"564.778898ms","start":"2026-02-10T15:03:52.723037Z","end":"2026-02-10T15:03:53.287816Z","steps":["trace[842843670] 'process raft request' (duration: 563.921433ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:53.287891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:52.723016Z","time spent":"564.82224ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:03:53.28806Z","caller":"traceutil/trace.go:171","msg":"trace[929615620] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"365.719265ms","start":"2026-02-10T15:03:52.922335Z","end":"2026-02-10T15:03:53.288054Z","steps":["trace[929615620] 'process raft request' (duration: 365.21173ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:53.289702Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:52.922316Z","time spent":"365.759566ms","remote":"127.0.0.1:43886","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3202,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T15:03:53.287732Z","caller":"traceutil/trace.go:171","msg":"trace[1527878473] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:586; }","duration":"593.135652ms","start":"2026-02-10T15:03:52.694586Z","end":"2026-02-10T15:03:53.287722Z","steps":["trace[1527878473] 'read index received' (duration: 407.067211ms)","trace[1527878473] 'applied index is now lower than readState.Index' (duration: 186.067771ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:03:53.289961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.368778ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:03:53.289982Z","caller":"traceutil/trace.go:171","msg":"trace[688314895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"595.4174ms","start":"2026-02-10T15:03:52.694558Z","end":"2026-02-10T15:03:53.289975Z","steps":["trace[688314895] 'agreement among raft nodes before linearized reading' (duration: 595.341898ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:03:53.290022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:03:52.694543Z","time spent":"595.474521ms","remote":"127.0.0.1:43844","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-02-10T15:03:53.323045Z","caller":"traceutil/trace.go:171","msg":"trace[681170228] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"201.146256ms","start":"2026-02-10T15:03:53.121878Z","end":"2026-02-10T15:03:53.323024Z","steps":["trace[681170228] 'process raft request' (duration: 200.496337ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:01.721843Z","caller":"traceutil/trace.go:171","msg":"trace[1350186666] transaction","detail":"{read_only:false; response_revision:655; number_of_response:1; }","duration":"110.285853ms","start":"2026-02-10T15:04:01.611542Z","end":"2026-02-10T15:04:01.721828Z","steps":["trace[1350186666] 'process raft request' (duration: 109.947973ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:02.011247Z","caller":"traceutil/trace.go:171","msg":"trace[1586070215] transaction","detail":"{read_only:false; response_revision:656; number_of_response:1; }","duration":"271.265406ms","start":"2026-02-10T15:04:01.739943Z","end":"2026-02-10T15:04:02.011209Z","steps":["trace[1586070215] 'process raft request' (duration: 271.168003ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:02.940281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.703719ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:04:02.940412Z","caller":"traceutil/trace.go:171","msg":"trace[1315743429] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"268.603539ms","start":"2026-02-10T15:04:02.671793Z","end":"2026-02-10T15:04:02.940396Z","steps":["trace[1315743429] 'read index received' (duration: 43.121µs)","trace[1315743429] 'applied index is now lower than readState.Index' (duration: 268.559138ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:02.940559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.754133ms","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-02-10T15:04:02.940586Z","caller":"traceutil/trace.go:171","msg":"trace[1359196425] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:672; }","duration":"268.810995ms","start":"2026-02-10T15:04:02.671765Z","end":"2026-02-10T15:04:02.940576Z","steps":["trace[1359196425] 'agreement among raft nodes before linearized reading' (duration: 268.674191ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:10.276549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"496.687108ms","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-02-10T15:04:10.276669Z","caller":"traceutil/trace.go:171","msg":"trace[148896915] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:734; }","duration":"496.817092ms","start":"2026-02-10T15:04:09.779834Z","end":"2026-02-10T15:04:10.276651Z","steps":["trace[148896915] 'range keys from in-memory index tree' (duration: 496.672907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:10.277371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.855542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:10.2774Z","caller":"traceutil/trace.go:171","msg":"trace[907238208] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"581.916693ms","start":"2026-02-10T15:04:09.695475Z","end":"2026-02-10T15:04:10.277392Z","steps":["trace[907238208] 'range keys from in-memory index tree' (duration: 581.78988ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:10.277429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:09.695456Z","time spent":"581.965315ms","remote":"127.0.0.1:43842","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-02-10T15:04:10.812909Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262229,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:04:10.973909Z","caller":"traceutil/trace.go:171","msg":"trace[2118930584] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"684.49267ms","start":"2026-02-10T15:04:10.2894Z","end":"2026-02-10T15:04:10.973893Z","steps":["trace[2118930584] 'process raft request' (duration: 684.394677ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:10.974038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.289389Z","time spent":"684.585963ms","remote":"127.0.0.1:44096","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-02-10T15:04:11.409327Z","caller":"traceutil/trace.go:171","msg":"trace[1001263220] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"1.096452584s","start":"2026-02-10T15:04:10.312853Z","end":"2026-02-10T15:04:11.409305Z","steps":["trace[1001263220] 'read index received' (duration: 661.089232ms)","trace[1001263220] 'applied index is now lower than readState.Index' (duration: 435.362352ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:04:11.409568Z","caller":"traceutil/trace.go:171","msg":"trace[51054267] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"790.358863ms","start":"2026-02-10T15:04:10.619199Z","end":"2026-02-10T15:04:11.409557Z","steps":["trace[51054267] 'process raft request' (duration: 789.992392ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:11.409665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.619174Z","time spent":"790.422365ms","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:04:11.409902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097051221s","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-02-10T15:04:11.409934Z","caller":"traceutil/trace.go:171","msg":"trace[1746017492] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:736; }","duration":"1.097129973s","start":"2026-02-10T15:04:10.312793Z","end":"2026-02-10T15:04:11.409923Z","steps":["trace[1746017492] 'agreement among raft nodes before linearized reading' (duration: 1.0970199s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:11.409961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.312776Z","time spent":"1.097178315s","remote":"127.0.0.1:44096","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-02-10T15:04:11.410421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.05208318s","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-02-10T15:04:11.410457Z","caller":"traceutil/trace.go:171","msg":"trace[1231513381] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.052144821s","start":"2026-02-10T15:04:10.358303Z","end":"2026-02-10T15:04:11.410447Z","steps":["trace[1231513381] 'agreement among raft nodes before linearized reading' (duration: 1.05177309s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:11.410485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.35829Z","time spent":"1.052188443s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:04:11.413723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"429.110399ms","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-02-10T15:04:11.413747Z","caller":"traceutil/trace.go:171","msg":"trace[1450735944] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:736; }","duration":"429.15675ms","start":"2026-02-10T15:04:10.984584Z","end":"2026-02-10T15:04:11.41374Z","steps":["trace[1450735944] 'agreement among raft nodes before linearized reading' (duration: 429.075578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:11.413768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.984573Z","time spent":"429.190562ms","remote":"127.0.0.1:44096","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-02-10T15:04:11.413879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.03744173s","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-02-10T15:04:11.413895Z","caller":"traceutil/trace.go:171","msg":"trace[933646468] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:736; }","duration":"1.037478111s","start":"2026-02-10T15:04:10.376412Z","end":"2026-02-10T15:04:11.41389Z","steps":["trace[933646468] 'agreement among raft nodes before linearized reading' (duration: 1.037431799s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:11.41391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:10.376401Z","time spent":"1.037506222s","remote":"127.0.0.1:44096","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-02-10T15:04:12.632197Z","caller":"traceutil/trace.go:171","msg":"trace[984893393] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"276.046899ms","start":"2026-02-10T15:04:12.356128Z","end":"2026-02-10T15:04:12.632175Z","steps":["trace[984893393] 'process raft request' (duration: 275.927655ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.496187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.18115ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:04:13.496312Z","caller":"traceutil/trace.go:171","msg":"trace[1828728672] linearizableReadLoop","detail":"{readStateIndex:768; appliedIndex:767; }","duration":"954.110967ms","start":"2026-02-10T15:04:12.542161Z","end":"2026-02-10T15:04:13.496272Z","steps":["trace[1828728672] 'read index received' (duration: 90.537322ms)","trace[1828728672] 'applied index is now lower than readState.Index' (duration: 863.572695ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:13.496402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.228631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.496423Z","caller":"traceutil/trace.go:171","msg":"trace[1330710181] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:742; }","duration":"954.280693ms","start":"2026-02-10T15:04:12.542135Z","end":"2026-02-10T15:04:13.496416Z","steps":["trace[1330710181] 'agreement among raft nodes before linearized reading' (duration: 954.228671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.496448Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.542109Z","time spent":"954.331204ms","remote":"127.0.0.1:51778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:04:13.496787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.934384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.496809Z","caller":"traceutil/trace.go:171","msg":"trace[1805641737] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:742; }","duration":"800.978525ms","start":"2026-02-10T15:04:12.695824Z","end":"2026-02-10T15:04:13.496802Z","steps":["trace[1805641737] 'agreement among raft nodes before linearized reading' (duration: 800.949244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.496826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.695813Z","time spent":"801.010146ms","remote":"127.0.0.1:43842","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-02-10T15:04:13.497398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"519.630292ms","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-02-10T15:04:13.497421Z","caller":"traceutil/trace.go:171","msg":"trace[1881041897] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"519.675582ms","start":"2026-02-10T15:04:12.977737Z","end":"2026-02-10T15:04:13.497413Z","steps":["trace[1881041897] 'agreement among raft nodes before linearized reading' (duration: 519.626761ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.497438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.977723Z","time spent":"519.710984ms","remote":"127.0.0.1:44096","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-02-10T15:04:13.497528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.926741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.497546Z","caller":"traceutil/trace.go:171","msg":"trace[724103169] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:742; }","duration":"654.014443ms","start":"2026-02-10T15:04:12.843526Z","end":"2026-02-10T15:04:13.49754Z","steps":["trace[724103169] 'agreement among raft nodes before linearized reading' (duration: 653.984742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.497563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.843477Z","time spent":"654.081286ms","remote":"127.0.0.1:44132","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:04:13.498106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"851.631194ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498129Z","caller":"traceutil/trace.go:171","msg":"trace[1896641528] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:742; }","duration":"851.662915ms","start":"2026-02-10T15:04:12.646459Z","end":"2026-02-10T15:04:13.498122Z","steps":["trace[1896641528] 'agreement among raft nodes before linearized reading' (duration: 851.625534ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.646457Z","time spent":"851.686696ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:04:13.49824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.941711ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498258Z","caller":"traceutil/trace.go:171","msg":"trace[237445505] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:742; }","duration":"838.965863ms","start":"2026-02-10T15:04:12.659286Z","end":"2026-02-10T15:04:13.498252Z","steps":["trace[237445505] 'agreement among raft nodes before linearized reading' (duration: 838.937142ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.659284Z","time spent":"838.987033ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:04:13.498361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.076526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498379Z","caller":"traceutil/trace.go:171","msg":"trace[679053795] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:742; }","duration":"839.102396ms","start":"2026-02-10T15:04:12.65927Z","end":"2026-02-10T15:04:13.498373Z","steps":["trace[679053795] 'agreement among raft nodes before linearized reading' (duration: 839.077626ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.659267Z","time spent":"839.123877ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 "} {"level":"warn","ts":"2026-02-10T15:04:13.498466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"839.20548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498483Z","caller":"traceutil/trace.go:171","msg":"trace[102836801] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:742; }","duration":"839.22239ms","start":"2026-02-10T15:04:12.659255Z","end":"2026-02-10T15:04:13.498477Z","steps":["trace[102836801] 'agreement among raft nodes before linearized reading' (duration: 839.19673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498499Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.659233Z","time spent":"839.261421ms","remote":"127.0.0.1:51796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":81,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:10000 "} {"level":"warn","ts":"2026-02-10T15:04:13.498759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.315104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498807Z","caller":"traceutil/trace.go:171","msg":"trace[1375821774] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:742; }","duration":"852.376866ms","start":"2026-02-10T15:04:12.646424Z","end":"2026-02-10T15:04:13.4988Z","steps":["trace[1375821774] 'agreement among raft nodes before linearized reading' (duration: 852.315274ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.646417Z","time spent":"852.404516ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 "} {"level":"warn","ts":"2026-02-10T15:04:13.498946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"852.485989ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.498977Z","caller":"traceutil/trace.go:171","msg":"trace[1497063583] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:742; }","duration":"852.51218ms","start":"2026-02-10T15:04:12.646447Z","end":"2026-02-10T15:04:13.498959Z","steps":["trace[1497063583] 'agreement among raft nodes before linearized reading' (duration: 852.483129ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.498996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.646445Z","time spent":"852.546001ms","remote":"127.0.0.1:51794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":105,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 "} {"level":"warn","ts":"2026-02-10T15:04:13.499172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"862.239116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.49919Z","caller":"traceutil/trace.go:171","msg":"trace[1425324588] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:742; }","duration":"862.288908ms","start":"2026-02-10T15:04:12.636896Z","end":"2026-02-10T15:04:13.499185Z","steps":["trace[1425324588] 'agreement among raft nodes before linearized reading' (duration: 862.259907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.499217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.632951Z","time spent":"866.261185ms","remote":"127.0.0.1:51778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:500 "} {"level":"warn","ts":"2026-02-10T15:04:13.499346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.499699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:13.499368Z","caller":"traceutil/trace.go:171","msg":"trace[886303808] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:742; }","duration":"957.119826ms","start":"2026-02-10T15:04:12.54224Z","end":"2026-02-10T15:04:13.49936Z","steps":["trace[886303808] 'agreement among raft nodes before linearized reading' (duration: 954.498179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.499391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:12.542235Z","time spent":"957.148837ms","remote":"127.0.0.1:51778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":95,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 "} {"level":"info","ts":"2026-02-10T15:04:13.653913Z","caller":"traceutil/trace.go:171","msg":"trace[666159866] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"151.075893ms","start":"2026-02-10T15:04:13.50282Z","end":"2026-02-10T15:04:13.653896Z","steps":["trace[666159866] 'process raft request' (duration: 150.919618ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:13.84103Z","caller":"traceutil/trace.go:171","msg":"trace[1164408198] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"336.275369ms","start":"2026-02-10T15:04:13.504738Z","end":"2026-02-10T15:04:13.841013Z","steps":["trace[1164408198] 'process raft request' (duration: 336.039963ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.841143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:13.504732Z","time spent":"336.346391ms","remote":"127.0.0.1:44096","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-02-10T15:04:13.841265Z","caller":"traceutil/trace.go:171","msg":"trace[301482903] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"338.00354ms","start":"2026-02-10T15:04:13.503254Z","end":"2026-02-10T15:04:13.841258Z","steps":["trace[301482903] 'process raft request' (duration: 337.139434ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.841307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:13.503246Z","time spent":"338.03814ms","remote":"127.0.0.1:44096","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-02-10T15:04:13.843238Z","caller":"traceutil/trace.go:171","msg":"trace[23936637] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"334.296291ms","start":"2026-02-10T15:04:13.508932Z","end":"2026-02-10T15:04:13.843229Z","steps":["trace[23936637] 'process raft request' (duration: 332.031584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.843301Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:13.508918Z","time spent":"334.344852ms","remote":"127.0.0.1:44096","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-02-10T15:04:13.843962Z","caller":"traceutil/trace.go:171","msg":"trace[526335795] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"333.633071ms","start":"2026-02-10T15:04:13.510295Z","end":"2026-02-10T15:04:13.843928Z","steps":["trace[526335795] 'process raft request' (duration: 333.5793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:13.844009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:13.510283Z","time spent":"333.702014ms","remote":"127.0.0.1:44096","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-02-10T15:04:30.192301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.701059ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:04:30.193231Z","caller":"traceutil/trace.go:171","msg":"trace[44786793] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"194.914297ms","start":"2026-02-10T15:04:29.998196Z","end":"2026-02-10T15:04:30.193111Z","steps":["trace[44786793] 'process raft request' (duration: 80.324777ms)","trace[44786793] 'compare' (duration: 113.597776ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:04:30.701895Z","caller":"traceutil/trace.go:171","msg":"trace[1663664677] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"703.638603ms","start":"2026-02-10T15:04:29.998245Z","end":"2026-02-10T15:04:30.701883Z","steps":["trace[1663664677] 'process raft request' (duration: 695.393885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:30.702003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:29.998231Z","time spent":"703.706315ms","remote":"127.0.0.1:44096","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-02-10T15:04:30.702252Z","caller":"traceutil/trace.go:171","msg":"trace[333351777] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"703.399268ms","start":"2026-02-10T15:04:29.998846Z","end":"2026-02-10T15:04:30.702246Z","steps":["trace[333351777] 'process raft request' (duration: 702.992628ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:30.702289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:29.998834Z","time spent":"703.436808ms","remote":"127.0.0.1:44096","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-02-10T15:04:30.703518Z","caller":"traceutil/trace.go:171","msg":"trace[418053935] linearizableReadLoop","detail":"{readStateIndex:827; appliedIndex:824; }","duration":"629.111636ms","start":"2026-02-10T15:04:30.074399Z","end":"2026-02-10T15:04:30.703511Z","steps":["trace[418053935] 'read index received' (duration: 4.126049ms)","trace[418053935] 'applied index is now lower than readState.Index' (duration: 623.343158ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:30.703589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.193048ms","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-02-10T15:04:30.70361Z","caller":"traceutil/trace.go:171","msg":"trace[293808002] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:797; }","duration":"629.27706ms","start":"2026-02-10T15:04:30.074327Z","end":"2026-02-10T15:04:30.703604Z","steps":["trace[293808002] 'agreement among raft nodes before linearized reading' (duration: 629.237099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:30.703626Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:30.074316Z","time spent":"629.30632ms","remote":"127.0.0.1:44308","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-02-10T15:04:30.703721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.899297ms","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":"info","ts":"2026-02-10T15:04:30.703741Z","caller":"traceutil/trace.go:171","msg":"trace[1246164991] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:797; }","duration":"532.951419ms","start":"2026-02-10T15:04:30.170784Z","end":"2026-02-10T15:04:30.703735Z","steps":["trace[1246164991] 'agreement among raft nodes before linearized reading' (duration: 532.922978ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:30.703758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:30.170768Z","time spent":"532.98539ms","remote":"127.0.0.1:44330","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-02-10T15:04:31.892394Z","caller":"traceutil/trace.go:171","msg":"trace[1549457351] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"138.111574ms","start":"2026-02-10T15:04:31.754265Z","end":"2026-02-10T15:04:31.892376Z","steps":["trace[1549457351] 'process raft request' (duration: 137.996001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:32.425699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.016975ms","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-02-10T15:04:32.425771Z","caller":"traceutil/trace.go:171","msg":"trace[1598563322] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:799; }","duration":"229.127857ms","start":"2026-02-10T15:04:32.196625Z","end":"2026-02-10T15:04:32.425753Z","steps":["trace[1598563322] 'range keys from in-memory index tree' (duration: 228.880311ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:32.425979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.143969ms","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-02-10T15:04:32.426014Z","caller":"traceutil/trace.go:171","msg":"trace[932559212] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:799; }","duration":"427.18037ms","start":"2026-02-10T15:04:31.998824Z","end":"2026-02-10T15:04:32.426004Z","steps":["trace[932559212] 'range keys from in-memory index tree' (duration: 427.004606ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:32.426041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:31.998773Z","time spent":"427.259521ms","remote":"127.0.0.1:44096","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-02-10T15:04:32.426108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.98515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:04:32.42614Z","caller":"traceutil/trace.go:171","msg":"trace[600470434] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:799; }","duration":"258.065102ms","start":"2026-02-10T15:04:32.168065Z","end":"2026-02-10T15:04:32.42613Z","steps":["trace[600470434] 'count revisions from in-memory index tree' (duration: 257.897478ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:33.193424Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262405,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:04:33.617506Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.186674164s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:04:33.618176Z","caller":"traceutil/trace.go:171","msg":"trace[785808186] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"1.187398262s","start":"2026-02-10T15:04:32.43075Z","end":"2026-02-10T15:04:33.618148Z","steps":["trace[785808186] 'process raft request' (duration: 1.187177377s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:33.618347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:32.430732Z","time spent":"1.187497265s","remote":"127.0.0.1:44096","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-02-10T15:04:34.425275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.145223ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:04:34.426462Z","caller":"traceutil/trace.go:171","msg":"trace[1628952926] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"1.993455793s","start":"2026-02-10T15:04:32.432795Z","end":"2026-02-10T15:04:34.42625Z","steps":["trace[1628952926] 'process raft request' (duration: 1.599231654s)","trace[1628952926] 'compare' (duration: 392.863636ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:34.426796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:32.432779Z","time spent":"1.993883483s","remote":"127.0.0.1:44096","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-02-10T15:04:34.693652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000215285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-10T15:04:34.6944Z","caller":"traceutil/trace.go:171","msg":"trace[2013854673] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001148527s","start":"2026-02-10T15:04:32.693222Z","end":"2026-02-10T15:04:34.69437Z","steps":["trace[2013854673] 'agreement among raft nodes before linearized reading' (duration: 2.000354078s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:34.694493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:32.693199Z","time spent":"2.001264859s","remote":"127.0.0.1:43842","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/10 15:04:34 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-10T15:04:34.964736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.346592ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:04:34.965023Z","caller":"traceutil/trace.go:171","msg":"trace[2001836857] linearizableReadLoop","detail":"{readStateIndex:832; appliedIndex:830; }","duration":"2.271621148s","start":"2026-02-10T15:04:32.693379Z","end":"2026-02-10T15:04:34.965Z","steps":["trace[2001836857] 'read index received' (duration: 925.569349ms)","trace[2001836857] 'applied index is now lower than readState.Index' (duration: 1.346047578s)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:34.965209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.259497657s","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-02-10T15:04:34.965224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.145963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:34.965237Z","caller":"traceutil/trace.go:171","msg":"trace[165085590] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"2.259567929s","start":"2026-02-10T15:04:32.70566Z","end":"2026-02-10T15:04:34.965228Z","steps":["trace[165085590] 'agreement among raft nodes before linearized reading' (duration: 2.259442996s)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:34.965255Z","caller":"traceutil/trace.go:171","msg":"trace[845353990] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:801; }","duration":"270.232425ms","start":"2026-02-10T15:04:34.695012Z","end":"2026-02-10T15:04:34.965245Z","steps":["trace[845353990] 'agreement among raft nodes before linearized reading' (duration: 270.182604ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:34.96527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:32.705644Z","time spent":"2.25961792s","remote":"127.0.0.1:44096","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-02-10T15:04:34.965412Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.33434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:04:34.965435Z","caller":"traceutil/trace.go:171","msg":"trace[1549245445] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:801; }","duration":"123.392982ms","start":"2026-02-10T15:04:34.842035Z","end":"2026-02-10T15:04:34.965428Z","steps":["trace[1549245445] 'agreement among raft nodes before linearized reading' (duration: 123.349101ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:34.965453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.067877264s","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-02-10T15:04:34.965481Z","caller":"traceutil/trace.go:171","msg":"trace[848304586] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:801; }","duration":"1.067937986s","start":"2026-02-10T15:04:33.897534Z","end":"2026-02-10T15:04:34.965472Z","steps":["trace[848304586] 'agreement among raft nodes before linearized reading' (duration: 1.067859184s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:34.965511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:33.897516Z","time spent":"1.067988167s","remote":"127.0.0.1:44096","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-02-10T15:04:34.965666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.258054672s","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-02-10T15:04:34.965691Z","caller":"traceutil/trace.go:171","msg":"trace[1693334672] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:801; }","duration":"2.258105213s","start":"2026-02-10T15:04:32.707578Z","end":"2026-02-10T15:04:34.965684Z","steps":["trace[1693334672] 'agreement among raft nodes before linearized reading' (duration: 2.258030941s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:34.965717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:32.707566Z","time spent":"2.258144784s","remote":"127.0.0.1:44096","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-02-10T15:04:35.152099Z","caller":"traceutil/trace.go:171","msg":"trace[723496984] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"180.333718ms","start":"2026-02-10T15:04:34.971743Z","end":"2026-02-10T15:04:35.152077Z","steps":["trace[723496984] 'process raft request' (duration: 180.218614ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:35.1593Z","caller":"traceutil/trace.go:171","msg":"trace[82214911] linearizableReadLoop","detail":"{readStateIndex:834; appliedIndex:833; }","duration":"187.396077ms","start":"2026-02-10T15:04:34.971892Z","end":"2026-02-10T15:04:35.159288Z","steps":["trace[82214911] 'read index received' (duration: 180.632395ms)","trace[82214911] 'applied index is now lower than readState.Index' (duration: 6.763032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:35.159388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.491789ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:35.159413Z","caller":"traceutil/trace.go:171","msg":"trace[1248874717] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:805; }","duration":"187.54594ms","start":"2026-02-10T15:04:34.971861Z","end":"2026-02-10T15:04:35.159407Z","steps":["trace[1248874717] 'agreement among raft nodes before linearized reading' (duration: 187.479739ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:35.159629Z","caller":"traceutil/trace.go:171","msg":"trace[983274214] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"187.853467ms","start":"2026-02-10T15:04:34.971769Z","end":"2026-02-10T15:04:35.159622Z","steps":["trace[983274214] 'process raft request' (duration: 187.404087ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:35.159718Z","caller":"traceutil/trace.go:171","msg":"trace[264626964] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"176.209798ms","start":"2026-02-10T15:04:34.983499Z","end":"2026-02-10T15:04:35.159709Z","steps":["trace[264626964] 'process raft request' (duration: 175.744267ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:04:35.159793Z","caller":"traceutil/trace.go:171","msg":"trace[1466848097] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"176.250849ms","start":"2026-02-10T15:04:34.983537Z","end":"2026-02-10T15:04:35.159788Z","steps":["trace[1466848097] 'process raft request' (duration: 175.730907ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:42.614782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.865579ms","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-02-10T15:04:42.614849Z","caller":"traceutil/trace.go:171","msg":"trace[1610967837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:824; }","duration":"161.940281ms","start":"2026-02-10T15:04:42.452895Z","end":"2026-02-10T15:04:42.614836Z","steps":["trace[1610967837] 'range keys from in-memory index tree' (duration: 161.515192ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:52.995708Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262621,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:04:53.332076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.018947897s","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-02-10T15:04:53.332135Z","caller":"traceutil/trace.go:171","msg":"trace[928593720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:897; }","duration":"4.01904219s","start":"2026-02-10T15:04:49.313078Z","end":"2026-02-10T15:04:53.33212Z","steps":["trace[928593720] 'range keys from in-memory index tree' (duration: 4.018825974s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.332175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:49.313064Z","time spent":"4.019093181s","remote":"127.0.0.1:44096","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-02-10T15:04:53.332478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.879115466s","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-02-10T15:04:53.332515Z","caller":"traceutil/trace.go:171","msg":"trace[1888541878] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:897; }","duration":"3.879183598s","start":"2026-02-10T15:04:49.453325Z","end":"2026-02-10T15:04:53.332509Z","steps":["trace[1888541878] 'range keys from in-memory index tree' (duration: 3.879027714s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.332534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:49.453307Z","time spent":"3.879220608s","remote":"127.0.0.1:44096","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-02-10T15:04:53.332692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.876284939s","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-02-10T15:04:53.332714Z","caller":"traceutil/trace.go:171","msg":"trace[1119656127] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:897; }","duration":"3.87632705s","start":"2026-02-10T15:04:49.45638Z","end":"2026-02-10T15:04:53.332707Z","steps":["trace[1119656127] 'range keys from in-memory index tree' (duration: 3.876213328s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.33273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:49.45637Z","time spent":"3.876355761s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:04:53.333071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.591300253s","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-02-10T15:04:53.333114Z","caller":"traceutil/trace.go:171","msg":"trace[1590771303] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:897; }","duration":"3.591372754s","start":"2026-02-10T15:04:49.741729Z","end":"2026-02-10T15:04:53.333102Z","steps":["trace[1590771303] 'range keys from in-memory index tree' (duration: 3.59119972s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.33315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:49.741714Z","time spent":"3.591416845s","remote":"127.0.0.1:44096","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-02-10T15:04:53.333236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310759613s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:53.333307Z","caller":"traceutil/trace.go:171","msg":"trace[866042009] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:897; }","duration":"1.310819744s","start":"2026-02-10T15:04:52.022434Z","end":"2026-02-10T15:04:53.333254Z","steps":["trace[866042009] 'count revisions from in-memory index tree' (duration: 1.310680601s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.333245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.5538095s","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-02-10T15:04:53.333327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:52.022417Z","time spent":"1.310903927s","remote":"127.0.0.1:44078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:04:53.33333Z","caller":"traceutil/trace.go:171","msg":"trace[301247365] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:897; }","duration":"3.553896672s","start":"2026-02-10T15:04:49.779428Z","end":"2026-02-10T15:04:53.333325Z","steps":["trace[301247365] 'range keys from in-memory index tree' (duration: 3.5538039s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.333719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.714621887s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:04:53.333734Z","caller":"traceutil/trace.go:171","msg":"trace[2089571525] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:897; }","duration":"2.714677848s","start":"2026-02-10T15:04:50.619052Z","end":"2026-02-10T15:04:53.333729Z","steps":["trace[2089571525] 'count revisions from in-memory index tree' (duration: 2.714544305s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.333748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:50.619014Z","time spent":"2.71473084s","remote":"127.0.0.1:44252","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:04:53.333855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.639314734s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:53.333869Z","caller":"traceutil/trace.go:171","msg":"trace[754573933] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:897; }","duration":"2.639363765s","start":"2026-02-10T15:04:50.694502Z","end":"2026-02-10T15:04:53.333865Z","steps":["trace[754573933] 'range keys from in-memory index tree' (duration: 2.639252123s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.333881Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:50.694483Z","time spent":"2.639396256s","remote":"127.0.0.1:43842","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-02-10T15:04:53.334126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.348504639s","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-02-10T15:04:53.33414Z","caller":"traceutil/trace.go:171","msg":"trace[1402963434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:897; }","duration":"2.34855032s","start":"2026-02-10T15:04:50.985585Z","end":"2026-02-10T15:04:53.334135Z","steps":["trace[1402963434] 'range keys from in-memory index tree' (duration: 2.348429507s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.334152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:50.985567Z","time spent":"2.348582261s","remote":"127.0.0.1:44096","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-02-10T15:04:53.356871Z","caller":"traceutil/trace.go:171","msg":"trace[1865052323] transaction","detail":"{read_only:false; response_revision:898; number_of_response:1; }","duration":"666.379555ms","start":"2026-02-10T15:04:52.690476Z","end":"2026-02-10T15:04:53.356855Z","steps":["trace[1865052323] 'process raft request' (duration: 665.942675ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.356976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:52.690458Z","time spent":"666.456158ms","remote":"127.0.0.1:44096","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-02-10T15:04:53.357064Z","caller":"traceutil/trace.go:171","msg":"trace[1013288682] linearizableReadLoop","detail":"{readStateIndex:934; appliedIndex:932; }","duration":"861.675222ms","start":"2026-02-10T15:04:52.495382Z","end":"2026-02-10T15:04:53.357057Z","steps":["trace[1013288682] 'read index received' (duration: 837.230756ms)","trace[1013288682] 'applied index is now lower than readState.Index' (duration: 24.443876ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:04:53.357156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"861.771174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:53.357176Z","caller":"traceutil/trace.go:171","msg":"trace[1032487043] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:899; }","duration":"861.816655ms","start":"2026-02-10T15:04:52.495354Z","end":"2026-02-10T15:04:53.357171Z","steps":["trace[1032487043] 'agreement among raft nodes before linearized reading' (duration: 861.782804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.357191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:52.495342Z","time spent":"861.846246ms","remote":"127.0.0.1:43842","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-02-10T15:04:53.369528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.544399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:04:53.369573Z","caller":"traceutil/trace.go:171","msg":"trace[2019561478] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:901; }","duration":"188.629361ms","start":"2026-02-10T15:04:53.180934Z","end":"2026-02-10T15:04:53.369564Z","steps":["trace[2019561478] 'agreement among raft nodes before linearized reading' (duration: 188.525248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.369711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.293691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:04:53.369732Z","caller":"traceutil/trace.go:171","msg":"trace[1478665987] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:901; }","duration":"674.970318ms","start":"2026-02-10T15:04:52.694756Z","end":"2026-02-10T15:04:53.369726Z","steps":["trace[1478665987] 'agreement among raft nodes before linearized reading' (duration: 674.295611ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:04:53.369746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:04:52.694742Z","time spent":"674.999678ms","remote":"127.0.0.1:43844","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-02-10T15:04:58.184747Z","caller":"traceutil/trace.go:171","msg":"trace[1790857059] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"238.242018ms","start":"2026-02-10T15:04:57.946483Z","end":"2026-02-10T15:04:58.184725Z","steps":["trace[1790857059] 'process raft request' (duration: 238.118075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:01.954851Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262691,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:05:02.235442Z","caller":"traceutil/trace.go:171","msg":"trace[2062555409] linearizableReadLoop","detail":"{readStateIndex:967; appliedIndex:966; }","duration":"781.582077ms","start":"2026-02-10T15:05:01.453838Z","end":"2026-02-10T15:05:02.23542Z","steps":["trace[2062555409] 'read index received' (duration: 781.076686ms)","trace[2062555409] 'applied index is now lower than readState.Index' (duration: 504.281µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:02.235896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"782.008618ms","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-02-10T15:05:02.236122Z","caller":"traceutil/trace.go:171","msg":"trace[2127459400] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:930; }","duration":"782.275994ms","start":"2026-02-10T15:05:01.453815Z","end":"2026-02-10T15:05:02.236091Z","steps":["trace[2127459400] 'agreement among raft nodes before linearized reading' (duration: 781.907885ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.236159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.453799Z","time spent":"782.349576ms","remote":"127.0.0.1:44096","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-02-10T15:05:02.237444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"783.569384ms","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-02-10T15:05:02.237486Z","caller":"traceutil/trace.go:171","msg":"trace[391160892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:930; }","duration":"783.602685ms","start":"2026-02-10T15:05:01.453859Z","end":"2026-02-10T15:05:02.237461Z","steps":["trace[391160892] 'agreement among raft nodes before linearized reading' (duration: 783.498202ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.237507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.453856Z","time spent":"783.645316ms","remote":"127.0.0.1:44096","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-02-10T15:05:02.235983Z","caller":"traceutil/trace.go:171","msg":"trace[1375850832] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"790.945109ms","start":"2026-02-10T15:05:01.444999Z","end":"2026-02-10T15:05:02.235944Z","steps":["trace[1375850832] 'process raft request' (duration: 790.088608ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.238001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.444987Z","time spent":"792.929754ms","remote":"127.0.0.1:44096","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-02-10T15:05:02.238482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.275099ms","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-02-10T15:05:02.238521Z","caller":"traceutil/trace.go:171","msg":"trace[1835868926] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:930; }","duration":"781.322981ms","start":"2026-02-10T15:05:01.457188Z","end":"2026-02-10T15:05:02.238511Z","steps":["trace[1835868926] 'agreement among raft nodes before linearized reading' (duration: 781.186647ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.238541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.457184Z","time spent":"781.352481ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T15:05:02.238644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.928335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:02.238662Z","caller":"traceutil/trace.go:171","msg":"trace[645912139] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:930; }","duration":"546.969685ms","start":"2026-02-10T15:05:01.691686Z","end":"2026-02-10T15:05:02.238656Z","steps":["trace[645912139] 'agreement among raft nodes before linearized reading' (duration: 546.941575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.238678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.691672Z","time spent":"547.002547ms","remote":"127.0.0.1:43842","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-02-10T15:05:02.241233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.721707ms","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-02-10T15:05:02.24129Z","caller":"traceutil/trace.go:171","msg":"trace[1070273176] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:930; }","duration":"786.779398ms","start":"2026-02-10T15:05:01.454502Z","end":"2026-02-10T15:05:02.241281Z","steps":["trace[1070273176] 'agreement among raft nodes before linearized reading' (duration: 786.638755ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.241313Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:01.454497Z","time spent":"786.80897ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:05:02.456761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.258834ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:02.456854Z","caller":"traceutil/trace.go:171","msg":"trace[786108290] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"212.549222ms","start":"2026-02-10T15:05:02.24429Z","end":"2026-02-10T15:05:02.45684Z","steps":["trace[786108290] 'read index received' (duration: 57.144574ms)","trace[786108290] 'applied index is now lower than readState.Index' (duration: 155.403648ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:02.45714Z","caller":"traceutil/trace.go:171","msg":"trace[621021373] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"213.853264ms","start":"2026-02-10T15:05:02.243274Z","end":"2026-02-10T15:05:02.457127Z","steps":["trace[621021373] 'process raft request' (duration: 58.139518ms)","trace[621021373] 'compare' (duration: 155.051589ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:02.457356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.060775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:02.457386Z","caller":"traceutil/trace.go:171","msg":"trace[1726677071] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:931; }","duration":"213.132487ms","start":"2026-02-10T15:05:02.244244Z","end":"2026-02-10T15:05:02.457376Z","steps":["trace[1726677071] 'agreement among raft nodes before linearized reading' (duration: 213.069875ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:02.957393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262699,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T15:05:03.214597Z","caller":"traceutil/trace.go:171","msg":"trace[635810071] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"961.642026ms","start":"2026-02-10T15:05:02.252941Z","end":"2026-02-10T15:05:03.214583Z","steps":["trace[635810071] 'process raft request' (duration: 961.617786ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.21469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.252935Z","time spent":"961.708678ms","remote":"127.0.0.1:44096","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-02-10T15:05:03.214951Z","caller":"traceutil/trace.go:171","msg":"trace[749175459] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"962.064586ms","start":"2026-02-10T15:05:02.252879Z","end":"2026-02-10T15:05:03.214944Z","steps":["trace[749175459] 'process raft request' (duration: 961.567924ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.214985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.252865Z","time spent":"962.103486ms","remote":"127.0.0.1:44096","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-02-10T15:05:03.21505Z","caller":"traceutil/trace.go:171","msg":"trace[1462349134] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"962.146928ms","start":"2026-02-10T15:05:02.252898Z","end":"2026-02-10T15:05:03.215045Z","steps":["trace[1462349134] 'process raft request' (duration: 961.633696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.215086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.252873Z","time spent":"962.187758ms","remote":"127.0.0.1:44096","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-02-10T15:05:03.22392Z","caller":"traceutil/trace.go:171","msg":"trace[1962150692] linearizableReadLoop","detail":"{readStateIndex:972; appliedIndex:971; }","duration":"767.008035ms","start":"2026-02-10T15:05:02.456877Z","end":"2026-02-10T15:05:03.223886Z","steps":["trace[1962150692] 'read index received' (duration: 758.188177ms)","trace[1962150692] 'applied index is now lower than readState.Index' (duration: 8.817848ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:03.224049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"937.111628ms","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":"info","ts":"2026-02-10T15:05:03.224107Z","caller":"traceutil/trace.go:171","msg":"trace[863080924] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:934; }","duration":"937.240031ms","start":"2026-02-10T15:05:02.286854Z","end":"2026-02-10T15:05:03.224094Z","steps":["trace[863080924] 'agreement among raft nodes before linearized reading' (duration: 937.134068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.224141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.286826Z","time spent":"937.302492ms","remote":"127.0.0.1:44288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":30,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:03.224305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.001149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:03.224338Z","caller":"traceutil/trace.go:171","msg":"trace[674448442] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"528.134122ms","start":"2026-02-10T15:05:02.696189Z","end":"2026-02-10T15:05:03.224323Z","steps":["trace[674448442] 'agreement among raft nodes before linearized reading' (duration: 527.961588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.224358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.696174Z","time spent":"528.179333ms","remote":"127.0.0.1:43842","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-02-10T15:05:03.224972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.815601ms","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-02-10T15:05:03.224998Z","caller":"traceutil/trace.go:171","msg":"trace[516251951] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:934; }","duration":"489.880872ms","start":"2026-02-10T15:05:02.735104Z","end":"2026-02-10T15:05:03.224985Z","steps":["trace[516251951] 'agreement among raft nodes before linearized reading' (duration: 489.837721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:03.225017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:02.73508Z","time spent":"489.932823ms","remote":"127.0.0.1:44294","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:04.629721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.52415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-10T15:05:04.629699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.354581ms","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-02-10T15:05:04.62976Z","caller":"traceutil/trace.go:171","msg":"trace[1858742383] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:935; }","duration":"169.586041ms","start":"2026-02-10T15:05:04.460163Z","end":"2026-02-10T15:05:04.629749Z","steps":["trace[1858742383] 'range keys from in-memory index tree' (duration: 169.425418ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:04.629778Z","caller":"traceutil/trace.go:171","msg":"trace[154386308] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:935; }","duration":"388.470274ms","start":"2026-02-10T15:05:04.241297Z","end":"2026-02-10T15:05:04.629768Z","steps":["trace[154386308] 'range keys from in-memory index tree' (duration: 388.239159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:04.629801Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:04.241284Z","time spent":"388.510585ms","remote":"127.0.0.1:44096","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-02-10T15:05:04.629955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.779048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:05:04.629975Z","caller":"traceutil/trace.go:171","msg":"trace[663563251] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:935; }","duration":"673.82506ms","start":"2026-02-10T15:05:03.956144Z","end":"2026-02-10T15:05:04.629969Z","steps":["trace[663563251] 'count revisions from in-memory index tree' (duration: 673.735088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:04.629993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:03.95613Z","time spent":"673.85709ms","remote":"127.0.0.1:43950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":4,"response size":30,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:04.630105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.204713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:05:04.630121Z","caller":"traceutil/trace.go:171","msg":"trace[1364496953] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:935; }","duration":"531.256124ms","start":"2026-02-10T15:05:04.09886Z","end":"2026-02-10T15:05:04.630116Z","steps":["trace[1364496953] 'count revisions from in-memory index tree' (duration: 531.163642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:04.630134Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:04.098847Z","time spent":"531.283255ms","remote":"127.0.0.1:44174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":69,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:06.435567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"743.202425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:06.435625Z","caller":"traceutil/trace.go:171","msg":"trace[240630561] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:940; }","duration":"743.294006ms","start":"2026-02-10T15:05:05.692318Z","end":"2026-02-10T15:05:06.435612Z","steps":["trace[240630561] 'range keys from in-memory index tree' (duration: 743.146984ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:06.435655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:05.692302Z","time spent":"743.343368ms","remote":"127.0.0.1:43842","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-02-10T15:05:06.435845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"632.880318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:06.435865Z","caller":"traceutil/trace.go:171","msg":"trace[1411581224] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:940; }","duration":"632.903378ms","start":"2026-02-10T15:05:05.802955Z","end":"2026-02-10T15:05:06.435859Z","steps":["trace[1411581224] 'count revisions from in-memory index tree' (duration: 632.836107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:06.435882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:05.80292Z","time spent":"632.957479ms","remote":"127.0.0.1:38228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:05:06.759929Z","caller":"traceutil/trace.go:171","msg":"trace[1659331852] linearizableReadLoop","detail":"{readStateIndex:979; appliedIndex:978; }","duration":"107.237845ms","start":"2026-02-10T15:05:06.652677Z","end":"2026-02-10T15:05:06.759915Z","steps":["trace[1659331852] 'read index received' (duration: 107.116071ms)","trace[1659331852] 'applied index is now lower than readState.Index' (duration: 121.294µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:06.760134Z","caller":"traceutil/trace.go:171","msg":"trace[1162188333] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"111.925305ms","start":"2026-02-10T15:05:06.648204Z","end":"2026-02-10T15:05:06.760129Z","steps":["trace[1162188333] 'process raft request' (duration: 111.627437ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:06.760288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.585783ms","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-02-10T15:05:06.760305Z","caller":"traceutil/trace.go:171","msg":"trace[1360831249] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:941; }","duration":"107.634854ms","start":"2026-02-10T15:05:06.652665Z","end":"2026-02-10T15:05:06.760299Z","steps":["trace[1360831249] 'agreement among raft nodes before linearized reading' (duration: 107.551892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.558736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.839149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:07.558784Z","caller":"traceutil/trace.go:171","msg":"trace[745507418] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:942; }","duration":"152.92669ms","start":"2026-02-10T15:05:07.405847Z","end":"2026-02-10T15:05:07.558773Z","steps":["trace[745507418] 'count revisions from in-memory index tree' (duration: 152.788847ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.559083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.777218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:05:07.559098Z","caller":"traceutil/trace.go:171","msg":"trace[593918579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:942; }","duration":"291.824839ms","start":"2026-02-10T15:05:07.267268Z","end":"2026-02-10T15:05:07.559093Z","steps":["trace[593918579] 'range keys from in-memory index tree' (duration: 291.665736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.559187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.372151ms","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-02-10T15:05:07.559199Z","caller":"traceutil/trace.go:171","msg":"trace[1079713568] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:942; }","duration":"286.384251ms","start":"2026-02-10T15:05:07.272811Z","end":"2026-02-10T15:05:07.559195Z","steps":["trace[1079713568] 'range keys from in-memory index tree' (duration: 286.290529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.559231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.654371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.191\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T15:05:07.55931Z","caller":"traceutil/trace.go:171","msg":"trace[1854965331] range","detail":"{range_begin:/registry/masterleases/199.19.213.191; range_end:; response_count:1; response_revision:942; }","duration":"434.757623ms","start":"2026-02-10T15:05:07.124531Z","end":"2026-02-10T15:05:07.559289Z","steps":["trace[1854965331] 'range keys from in-memory index tree' (duration: 434.526028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.559358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:07.124517Z","time spent":"434.828225ms","remote":"127.0.0.1:43868","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.191\" "} {"level":"warn","ts":"2026-02-10T15:05:07.559275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.212844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-02-10T15:05:07.559509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.931447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:05:07.559528Z","caller":"traceutil/trace.go:171","msg":"trace[239558432] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:942; }","duration":"285.48497ms","start":"2026-02-10T15:05:07.274033Z","end":"2026-02-10T15:05:07.559518Z","steps":["trace[239558432] 'range keys from in-memory index tree' (duration: 285.156492ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:07.559541Z","caller":"traceutil/trace.go:171","msg":"trace[1291261146] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:942; }","duration":"397.989158ms","start":"2026-02-10T15:05:07.161546Z","end":"2026-02-10T15:05:07.559535Z","steps":["trace[1291261146] 'count revisions from in-memory index tree' (duration: 397.874705ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.559557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:07.161532Z","time spent":"398.020349ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":7,"response size":30,"request content":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true "} {"level":"info","ts":"2026-02-10T15:05:07.927033Z","caller":"traceutil/trace.go:171","msg":"trace[136927580] linearizableReadLoop","detail":"{readStateIndex:984; appliedIndex:980; }","duration":"230.573317ms","start":"2026-02-10T15:05:07.696439Z","end":"2026-02-10T15:05:07.927012Z","steps":["trace[136927580] 'read index received' (duration: 36.187642ms)","trace[136927580] 'applied index is now lower than readState.Index' (duration: 194.384795ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:07.927171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.7121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:07.927215Z","caller":"traceutil/trace.go:171","msg":"trace[551062574] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:945; }","duration":"230.793782ms","start":"2026-02-10T15:05:07.696412Z","end":"2026-02-10T15:05:07.927206Z","steps":["trace[551062574] 'agreement among raft nodes before linearized reading' (duration: 230.694789ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:07.927586Z","caller":"traceutil/trace.go:171","msg":"trace[1385361824] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"364.040749ms","start":"2026-02-10T15:05:07.56353Z","end":"2026-02-10T15:05:07.927571Z","steps":["trace[1385361824] 'process raft request' (duration: 363.172959ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:07.929261Z","caller":"traceutil/trace.go:171","msg":"trace[1960387048] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"195.523113ms","start":"2026-02-10T15:05:07.73372Z","end":"2026-02-10T15:05:07.929243Z","steps":["trace[1960387048] 'process raft request' (duration: 195.339189ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.927696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:07.563521Z","time spent":"364.113101ms","remote":"127.0.0.1:44096","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-02-10T15:05:07.930218Z","caller":"traceutil/trace.go:171","msg":"trace[849803803] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"366.329303ms","start":"2026-02-10T15:05:07.563858Z","end":"2026-02-10T15:05:07.930187Z","steps":["trace[849803803] 'process raft request' (duration: 362.998055ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.930323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:07.563852Z","time spent":"366.411535ms","remote":"127.0.0.1:44096","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-02-10T15:05:07.930406Z","caller":"traceutil/trace.go:171","msg":"trace[1758120748] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"363.202009ms","start":"2026-02-10T15:05:07.567198Z","end":"2026-02-10T15:05:07.9304Z","steps":["trace[1758120748] 'process raft request' (duration: 359.779208ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:07.930471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:07.567181Z","time spent":"363.237759ms","remote":"127.0.0.1:44096","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-02-10T15:05:10.404689Z","caller":"traceutil/trace.go:171","msg":"trace[128765025] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"456.355932ms","start":"2026-02-10T15:05:09.948305Z","end":"2026-02-10T15:05:10.404661Z","steps":["trace[128765025] 'process raft request' (duration: 456.192957ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:10.404925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:09.948293Z","time spent":"456.482605ms","remote":"127.0.0.1:44096","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-02-10T15:05:11.449671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"893.045331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:11.449774Z","caller":"traceutil/trace.go:171","msg":"trace[32535368] linearizableReadLoop","detail":"{readStateIndex:991; appliedIndex:989; }","duration":"1.404831608s","start":"2026-02-10T15:05:10.04493Z","end":"2026-02-10T15:05:11.449762Z","steps":["trace[32535368] 'read index received' (duration: 360.821133ms)","trace[32535368] 'applied index is now lower than readState.Index' (duration: 1.044009955s)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:11.449901Z","caller":"traceutil/trace.go:171","msg":"trace[1918537532] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"1.501013791s","start":"2026-02-10T15:05:09.94888Z","end":"2026-02-10T15:05:11.449894Z","steps":["trace[1918537532] 'process raft request' (duration: 607.702014ms)","trace[1918537532] 'compare' (duration: 892.603079ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:11.449936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:09.948872Z","time spent":"1.501046362s","remote":"127.0.0.1:44096","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-02-10T15:05:11.450049Z","caller":"traceutil/trace.go:171","msg":"trace[833960806] transaction","detail":"{read_only:false; response_revision:952; number_of_response:1; }","duration":"1.499198088s","start":"2026-02-10T15:05:09.950846Z","end":"2026-02-10T15:05:11.450044Z","steps":["trace[833960806] 'process raft request' (duration: 1.498881351s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:11.450073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:09.950656Z","time spent":"1.499403664s","remote":"127.0.0.1:44096","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-02-10T15:05:11.450164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405232547s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:11.450181Z","caller":"traceutil/trace.go:171","msg":"trace[1500797215] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:952; }","duration":"1.405274628s","start":"2026-02-10T15:05:10.044902Z","end":"2026-02-10T15:05:11.450177Z","steps":["trace[1500797215] 'agreement among raft nodes before linearized reading' (duration: 1.405238938s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:11.450196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:10.044889Z","time spent":"1.405303279s","remote":"127.0.0.1:44210","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:11.450299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.566262ms","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-02-10T15:05:11.450315Z","caller":"traceutil/trace.go:171","msg":"trace[2120377543] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:952; }","duration":"650.611013ms","start":"2026-02-10T15:05:10.7997Z","end":"2026-02-10T15:05:11.450311Z","steps":["trace[2120377543] 'agreement among raft nodes before linearized reading' (duration: 650.553882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:11.45033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:10.799685Z","time spent":"650.641014ms","remote":"127.0.0.1:44096","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-02-10T15:05:11.450471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.112946ms","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-02-10T15:05:11.450487Z","caller":"traceutil/trace.go:171","msg":"trace[2069989199] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:952; }","duration":"558.182138ms","start":"2026-02-10T15:05:10.892301Z","end":"2026-02-10T15:05:11.450483Z","steps":["trace[2069989199] 'agreement among raft nodes before linearized reading' (duration: 558.142848ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:11.450498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:10.892284Z","time spent":"558.211819ms","remote":"127.0.0.1:44096","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-02-10T15:05:12.004144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.644263ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:12.004241Z","caller":"traceutil/trace.go:171","msg":"trace[1319212902] linearizableReadLoop","detail":"{readStateIndex:993; appliedIndex:991; }","duration":"312.425014ms","start":"2026-02-10T15:05:11.691806Z","end":"2026-02-10T15:05:12.004231Z","steps":["trace[1319212902] 'read index received' (duration: 254.404598ms)","trace[1319212902] 'applied index is now lower than readState.Index' (duration: 58.019916ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:12.004269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.468596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:12.00428Z","caller":"traceutil/trace.go:171","msg":"trace[1809907194] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:954; }","duration":"312.501716ms","start":"2026-02-10T15:05:11.691774Z","end":"2026-02-10T15:05:12.004276Z","steps":["trace[1809907194] 'agreement among raft nodes before linearized reading' (duration: 312.482185ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:12.004295Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:11.691747Z","time spent":"312.544847ms","remote":"127.0.0.1:43842","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-02-10T15:05:12.004406Z","caller":"traceutil/trace.go:171","msg":"trace[902061535] transaction","detail":"{read_only:false; response_revision:954; number_of_response:1; }","duration":"545.857949ms","start":"2026-02-10T15:05:11.458543Z","end":"2026-02-10T15:05:12.004401Z","steps":["trace[902061535] 'process raft request' (duration: 545.658114ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:12.004395Z","caller":"traceutil/trace.go:171","msg":"trace[333464981] transaction","detail":"{read_only:false; response_revision:953; number_of_response:1; }","duration":"549.810282ms","start":"2026-02-10T15:05:11.454565Z","end":"2026-02-10T15:05:12.004376Z","steps":["trace[333464981] 'process raft request' (duration: 142.887943ms)","trace[333464981] 'compare' (duration: 406.402766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:12.004437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:11.458535Z","time spent":"545.884419ms","remote":"127.0.0.1:44096","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-02-10T15:05:12.004466Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:11.454551Z","time spent":"549.877833ms","remote":"127.0.0.1:44096","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-02-10T15:05:12.952905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.861255ms","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-02-10T15:05:12.95315Z","caller":"traceutil/trace.go:171","msg":"trace[1230047548] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:954; }","duration":"545.150212ms","start":"2026-02-10T15:05:12.407987Z","end":"2026-02-10T15:05:12.953138Z","steps":["trace[1230047548] 'range keys from in-memory index tree' (duration: 544.746332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:12.953179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:12.407975Z","time spent":"545.198613ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T15:05:13.487809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.764682ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T15:05:14.00654Z","caller":"traceutil/trace.go:171","msg":"trace[1223537778] linearizableReadLoop","detail":"{readStateIndex:995; appliedIndex:993; }","duration":"1.311386147s","start":"2026-02-10T15:05:12.695136Z","end":"2026-02-10T15:05:14.006522Z","steps":["trace[1223537778] 'read index received' (duration: 412.042988ms)","trace[1223537778] 'applied index is now lower than readState.Index' (duration: 899.342619ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:14.006725Z","caller":"traceutil/trace.go:171","msg":"trace[1700771608] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"1.349542415s","start":"2026-02-10T15:05:12.657177Z","end":"2026-02-10T15:05:14.00672Z","steps":["trace[1700771608] 'process raft request' (duration: 1.349217117s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.006796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:12.657161Z","time spent":"1.349579736s","remote":"127.0.0.1:44010","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5143,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:05:14.006957Z","caller":"traceutil/trace.go:171","msg":"trace[1756522263] transaction","detail":"{read_only:false; response_revision:956; number_of_response:1; }","duration":"1.049197985s","start":"2026-02-10T15:05:12.957752Z","end":"2026-02-10T15:05:14.00695Z","steps":["trace[1756522263] 'process raft request' (duration: 1.048744295s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.006982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:12.957738Z","time spent":"1.049230546s","remote":"127.0.0.1:44096","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-02-10T15:05:14.00705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.311913879s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:14.007065Z","caller":"traceutil/trace.go:171","msg":"trace[154826114] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:956; }","duration":"1.31192815s","start":"2026-02-10T15:05:12.695132Z","end":"2026-02-10T15:05:14.00706Z","steps":["trace[154826114] 'agreement among raft nodes before linearized reading' (duration: 1.311900069s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.007078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:12.695098Z","time spent":"1.311976972s","remote":"127.0.0.1:43842","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-02-10T15:05:14.266649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.211568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:14.266715Z","caller":"traceutil/trace.go:171","msg":"trace[270140995] linearizableReadLoop","detail":"{readStateIndex:997; appliedIndex:996; }","duration":"260.120272ms","start":"2026-02-10T15:05:14.006586Z","end":"2026-02-10T15:05:14.266706Z","steps":["trace[270140995] 'read index received' (duration: 114.743391ms)","trace[270140995] 'applied index is now lower than readState.Index' (duration: 145.376141ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:14.266838Z","caller":"traceutil/trace.go:171","msg":"trace[722149031] transaction","detail":"{read_only:false; response_revision:957; number_of_response:1; }","duration":"530.392704ms","start":"2026-02-10T15:05:13.736439Z","end":"2026-02-10T15:05:14.266832Z","steps":["trace[722149031] 'process raft request' (duration: 384.952871ms)","trace[722149031] 'compare' (duration: 145.146766ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:14.266867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:13.736424Z","time spent":"530.427935ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:05:14.267037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"500.354347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:14.267125Z","caller":"traceutil/trace.go:171","msg":"trace[707457929] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:957; }","duration":"500.464809ms","start":"2026-02-10T15:05:13.766653Z","end":"2026-02-10T15:05:14.267118Z","steps":["trace[707457929] 'agreement among raft nodes before linearized reading' (duration: 500.366407ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.267141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:13.766642Z","time spent":"500.49471ms","remote":"127.0.0.1:44074","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:14.267194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.848146ms","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-02-10T15:05:14.267243Z","caller":"traceutil/trace.go:171","msg":"trace[773175599] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:957; }","duration":"245.941008ms","start":"2026-02-10T15:05:14.02129Z","end":"2026-02-10T15:05:14.267231Z","steps":["trace[773175599] 'agreement among raft nodes before linearized reading' (duration: 245.775985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.26736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.370654ms","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-02-10T15:05:14.267377Z","caller":"traceutil/trace.go:171","msg":"trace[132275913] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:957; }","duration":"810.423016ms","start":"2026-02-10T15:05:13.45695Z","end":"2026-02-10T15:05:14.267373Z","steps":["trace[132275913] 'agreement among raft nodes before linearized reading' (duration: 810.357124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.26739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:13.45693Z","time spent":"810.456356ms","remote":"127.0.0.1:44096","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-02-10T15:05:14.267439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.594338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:05:14.26745Z","caller":"traceutil/trace.go:171","msg":"trace[1550334072] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:957; }","duration":"544.607648ms","start":"2026-02-10T15:05:13.722839Z","end":"2026-02-10T15:05:14.267446Z","steps":["trace[1550334072] 'agreement among raft nodes before linearized reading' (duration: 544.580758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.267464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:13.722803Z","time spent":"544.65628ms","remote":"127.0.0.1:44370","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:14.267482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.089121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:14.267498Z","caller":"traceutil/trace.go:171","msg":"trace[1962744964] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:957; }","duration":"246.110973ms","start":"2026-02-10T15:05:14.021382Z","end":"2026-02-10T15:05:14.267493Z","steps":["trace[1962744964] 'agreement among raft nodes before linearized reading' (duration: 246.073642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.267564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"810.539318ms","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-02-10T15:05:14.267579Z","caller":"traceutil/trace.go:171","msg":"trace[1675997875] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:957; }","duration":"810.570338ms","start":"2026-02-10T15:05:13.457005Z","end":"2026-02-10T15:05:14.267576Z","steps":["trace[1675997875] 'agreement among raft nodes before linearized reading' (duration: 810.530148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.267595Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.253526ms","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-02-10T15:05:14.267602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:13.457Z","time spent":"810.588809ms","remote":"127.0.0.1:44096","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-02-10T15:05:14.267612Z","caller":"traceutil/trace.go:171","msg":"trace[658338977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:957; }","duration":"246.275877ms","start":"2026-02-10T15:05:14.021331Z","end":"2026-02-10T15:05:14.267606Z","steps":["trace[658338977] 'agreement among raft nodes before linearized reading' (duration: 246.229515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:14.611159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.823882ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:14.611273Z","caller":"traceutil/trace.go:171","msg":"trace[1641265510] transaction","detail":"{read_only:false; response_revision:958; number_of_response:1; }","duration":"335.065086ms","start":"2026-02-10T15:05:14.276179Z","end":"2026-02-10T15:05:14.611244Z","steps":["trace[1641265510] 'process raft request' (duration: 158.07336ms)","trace[1641265510] 'compare' (duration: 176.600107ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:14.611345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.276165Z","time spent":"335.151448ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T15:05:15.139685Z","caller":"traceutil/trace.go:171","msg":"trace[983448627] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:997; }","duration":"855.753234ms","start":"2026-02-10T15:05:14.283919Z","end":"2026-02-10T15:05:15.139672Z","steps":["trace[983448627] 'read index received' (duration: 150.468913ms)","trace[983448627] 'applied index is now lower than readState.Index' (duration: 705.283831ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:15.139833Z","caller":"traceutil/trace.go:171","msg":"trace[1369297343] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"862.062551ms","start":"2026-02-10T15:05:14.277765Z","end":"2026-02-10T15:05:15.139827Z","steps":["trace[1369297343] 'process raft request' (duration: 861.795725ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:15.139895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.27774Z","time spent":"862.106833ms","remote":"127.0.0.1:44096","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-02-10T15:05:15.139988Z","caller":"traceutil/trace.go:171","msg":"trace[686280478] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"856.801948ms","start":"2026-02-10T15:05:14.283181Z","end":"2026-02-10T15:05:15.139983Z","steps":["trace[686280478] 'process raft request' (duration: 856.45204ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:15.140011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.283173Z","time spent":"856.825008ms","remote":"127.0.0.1:44096","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-02-10T15:05:15.140068Z","caller":"traceutil/trace.go:171","msg":"trace[1752347434] transaction","detail":"{read_only:false; response_revision:961; number_of_response:1; }","duration":"856.831539ms","start":"2026-02-10T15:05:14.283231Z","end":"2026-02-10T15:05:15.140063Z","steps":["trace[1752347434] 'process raft request' (duration: 856.422539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:15.140093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.283224Z","time spent":"856.854779ms","remote":"127.0.0.1:44096","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-02-10T15:05:15.140155Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.235205ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:15.140169Z","caller":"traceutil/trace.go:171","msg":"trace[2055248023] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:961; }","duration":"856.268125ms","start":"2026-02-10T15:05:14.283896Z","end":"2026-02-10T15:05:15.140164Z","steps":["trace[2055248023] 'agreement among raft nodes before linearized reading' (duration: 856.244014ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:15.140183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.28389Z","time spent":"856.290375ms","remote":"127.0.0.1:43842","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-02-10T15:05:15.140265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.051247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-10T15:05:15.14028Z","caller":"traceutil/trace.go:171","msg":"trace[30934084] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:961; }","duration":"533.088658ms","start":"2026-02-10T15:05:14.607185Z","end":"2026-02-10T15:05:15.140274Z","steps":["trace[30934084] 'agreement among raft nodes before linearized reading' (duration: 533.064217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:15.140293Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:14.607171Z","time spent":"533.117939ms","remote":"127.0.0.1:38212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":3,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-02-10T15:05:17.116494Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13903909782983262782,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-10T15:05:17.159079Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.146758903s","expected-duration":"1s"} {"level":"info","ts":"2026-02-10T15:05:17.159304Z","caller":"traceutil/trace.go:171","msg":"trace[1778619570] linearizableReadLoop","detail":"{readStateIndex:1002; appliedIndex:1001; }","duration":"544.010515ms","start":"2026-02-10T15:05:16.615277Z","end":"2026-02-10T15:05:17.159288Z","steps":["trace[1778619570] 'read index received' (duration: 543.883112ms)","trace[1778619570] 'applied index is now lower than readState.Index' (duration: 126.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T15:05:17.159477Z","caller":"traceutil/trace.go:171","msg":"trace[434849365] transaction","detail":"{read_only:false; response_revision:962; number_of_response:1; }","duration":"1.147227784s","start":"2026-02-10T15:05:16.01224Z","end":"2026-02-10T15:05:17.159467Z","steps":["trace[434849365] 'process raft request' (duration: 1.146965608s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:17.159547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:16.01223Z","time spent":"1.147265085s","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T15:05:17.159716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"544.441236ms","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-02-10T15:05:17.159737Z","caller":"traceutil/trace.go:171","msg":"trace[1640435717] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:962; }","duration":"544.485346ms","start":"2026-02-10T15:05:16.615245Z","end":"2026-02-10T15:05:17.15973Z","steps":["trace[1640435717] 'agreement among raft nodes before linearized reading' (duration: 544.405684ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:17.159756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:16.615231Z","time spent":"544.521018ms","remote":"127.0.0.1:44096","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-02-10T15:05:17.159842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.003166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:17.159859Z","caller":"traceutil/trace.go:171","msg":"trace[100384524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:962; }","duration":"468.043377ms","start":"2026-02-10T15:05:16.69181Z","end":"2026-02-10T15:05:17.159853Z","steps":["trace[100384524] 'agreement among raft nodes before linearized reading' (duration: 468.019276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:17.159874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:16.691794Z","time spent":"468.076617ms","remote":"127.0.0.1:43842","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-02-10T15:05:18.042422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.097465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T15:05:18.042661Z","caller":"traceutil/trace.go:171","msg":"trace[1465543055] linearizableReadLoop","detail":"{readStateIndex:1007; appliedIndex:1002; }","duration":"343.125426ms","start":"2026-02-10T15:05:17.699524Z","end":"2026-02-10T15:05:18.042649Z","steps":["trace[1465543055] 'read index received' (duration: 237.758206ms)","trace[1465543055] 'applied index is now lower than readState.Index' (duration: 105.36667ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:18.042711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.184607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:05:18.042726Z","caller":"traceutil/trace.go:171","msg":"trace[375565797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"343.233539ms","start":"2026-02-10T15:05:17.699487Z","end":"2026-02-10T15:05:18.04272Z","steps":["trace[375565797] 'agreement among raft nodes before linearized reading' (duration: 343.193148ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:18.042743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.699474Z","time spent":"343.265439ms","remote":"127.0.0.1:43842","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-02-10T15:05:18.042932Z","caller":"traceutil/trace.go:171","msg":"trace[1603884151] transaction","detail":"{read_only:false; response_revision:963; number_of_response:1; }","duration":"874.570775ms","start":"2026-02-10T15:05:17.168351Z","end":"2026-02-10T15:05:18.042922Z","steps":["trace[1603884151] 'process raft request' (duration: 768.923568ms)","trace[1603884151] 'compare' (duration: 104.731596ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:18.042967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.168342Z","time spent":"874.606776ms","remote":"127.0.0.1:44096","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-02-10T15:05:18.043032Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.169273Z","time spent":"873.758536ms","remote":"127.0.0.1:43868","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-10T15:05:18.043105Z","caller":"traceutil/trace.go:171","msg":"trace[1068279835] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"873.356568ms","start":"2026-02-10T15:05:17.169743Z","end":"2026-02-10T15:05:18.043099Z","steps":["trace[1068279835] 'process raft request' (duration: 872.833805ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:18.043144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.169731Z","time spent":"873.394038ms","remote":"127.0.0.1:44096","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-02-10T15:05:18.043211Z","caller":"traceutil/trace.go:171","msg":"trace[1269770664] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"863.560396ms","start":"2026-02-10T15:05:17.179645Z","end":"2026-02-10T15:05:18.043206Z","steps":["trace[1269770664] 'process raft request' (duration: 862.964312ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:18.043236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.179628Z","time spent":"863.593997ms","remote":"127.0.0.1:44096","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-02-10T15:05:18.043294Z","caller":"traceutil/trace.go:171","msg":"trace[1348949921] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"862.197774ms","start":"2026-02-10T15:05:17.181091Z","end":"2026-02-10T15:05:18.043289Z","steps":["trace[1348949921] 'process raft request' (duration: 861.539429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:18.04332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:17.181078Z","time spent":"862.228644ms","remote":"127.0.0.1:44096","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-02-10T15:05:19.058852Z","caller":"traceutil/trace.go:171","msg":"trace[1121804080] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"283.839862ms","start":"2026-02-10T15:05:18.774989Z","end":"2026-02-10T15:05:19.058829Z","steps":["trace[1121804080] 'process raft request' (duration: 283.692218ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:26.293146Z","caller":"traceutil/trace.go:171","msg":"trace[1416526372] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"151.586979ms","start":"2026-02-10T15:05:26.141539Z","end":"2026-02-10T15:05:26.293126Z","steps":["trace[1416526372] 'read index received' (duration: 151.421905ms)","trace[1416526372] 'applied index is now lower than readState.Index' (duration: 164.514µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T15:05:26.293492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.936187ms","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-02-10T15:05:26.293514Z","caller":"traceutil/trace.go:171","msg":"trace[1047069339] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:998; }","duration":"151.984287ms","start":"2026-02-10T15:05:26.141523Z","end":"2026-02-10T15:05:26.293507Z","steps":["trace[1047069339] 'agreement among raft nodes before linearized reading' (duration: 151.891555ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:26.293617Z","caller":"traceutil/trace.go:171","msg":"trace[421934292] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"153.557664ms","start":"2026-02-10T15:05:26.140051Z","end":"2026-02-10T15:05:26.293609Z","steps":["trace[421934292] 'process raft request' (duration: 152.96777ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:26.29361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.63449ms","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-02-10T15:05:26.293694Z","caller":"traceutil/trace.go:171","msg":"trace[1391996780] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:998; }","duration":"151.756052ms","start":"2026-02-10T15:05:26.141916Z","end":"2026-02-10T15:05:26.293672Z","steps":["trace[1391996780] 'agreement among raft nodes before linearized reading' (duration: 151.488736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:26.293801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.022674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-10T15:05:26.293819Z","caller":"traceutil/trace.go:171","msg":"trace[641959604] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:998; }","duration":"148.049744ms","start":"2026-02-10T15:05:26.145764Z","end":"2026-02-10T15:05:26.293814Z","steps":["trace[641959604] 'agreement among raft nodes before linearized reading' (duration: 147.999813ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:26.50717Z","caller":"traceutil/trace.go:171","msg":"trace[512819682] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"200.771397ms","start":"2026-02-10T15:05:26.306375Z","end":"2026-02-10T15:05:26.507147Z","steps":["trace[512819682] 'process raft request' (duration: 198.426231ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:26.510117Z","caller":"traceutil/trace.go:171","msg":"trace[2060494638] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"202.472136ms","start":"2026-02-10T15:05:26.307633Z","end":"2026-02-10T15:05:26.510105Z","steps":["trace[2060494638] 'process raft request' (duration: 202.444735ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:05:26.510291Z","caller":"traceutil/trace.go:171","msg":"trace[1184185007] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"203.090991ms","start":"2026-02-10T15:05:26.307192Z","end":"2026-02-10T15:05:26.510283Z","steps":["trace[1184185007] 'process raft request' (duration: 202.825635ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:29.725325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.750135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T15:05:29.725383Z","caller":"traceutil/trace.go:171","msg":"trace[296716044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1012; }","duration":"477.843718ms","start":"2026-02-10T15:05:29.247525Z","end":"2026-02-10T15:05:29.725368Z","steps":["trace[296716044] 'range keys from in-memory index tree' (duration: 477.650213ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:05:29.725406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T15:05:29.247506Z","time spent":"477.894388ms","remote":"127.0.0.1:44096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-10T15:07:53.82073Z","caller":"traceutil/trace.go:171","msg":"trace[454376798] transaction","detail":"{read_only:false; response_revision:1700; number_of_response:1; }","duration":"134.800911ms","start":"2026-02-10T15:07:53.685915Z","end":"2026-02-10T15:07:53.820716Z","steps":["trace[454376798] 'process raft request' (duration: 134.491844ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T15:07:53.821199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.737777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T15:07:53.821235Z","caller":"traceutil/trace.go:171","msg":"trace[1874150344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1700; }","duration":"126.80184ms","start":"2026-02-10T15:07:53.694422Z","end":"2026-02-10T15:07:53.821224Z","steps":["trace[1874150344] 'agreement among raft nodes before linearized reading' (duration: 126.730128ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T15:07:53.821097Z","caller":"traceutil/trace.go:171","msg":"trace[1473122586] linearizableReadLoop","detail":"{readStateIndex:1773; appliedIndex:1772; }","duration":"126.091453ms","start":"2026-02-10T15:07:53.694448Z","end":"2026-02-10T15:07:53.820539Z","steps":["trace[1473122586] 'read index received' (duration: 125.918999ms)","trace[1473122586] 'applied index is now lower than readState.Index' (duration: 171.544µs)"],"step_count":2}