{"level":"warn","ts":"2026-02-12T17:00:18.488071Z","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-12T17:00:18.489804Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.17: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.17:2380","--initial-cluster=instance=https://199.19.213.17:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.17:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.17: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-12T17:00:18.489999Z","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-12T17:00:18.490168Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-02-12T17:00:18.49022Z","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-12T17:00:18.496996Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"]} {"level":"info","ts":"2026-02-12T17:00:18.497222Z","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.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.17: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-12T17:00:18.524117Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"26.561283ms"} {"level":"info","ts":"2026-02-12T17:00:18.543305Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"27c4b87f3ea730e3","cluster-id":"cdce983f4a7486fe"} {"level":"info","ts":"2026-02-12T17:00:18.543576Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=()"} {"level":"info","ts":"2026-02-12T17:00:18.543694Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 0"} {"level":"info","ts":"2026-02-12T17:00:18.543774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 27c4b87f3ea730e3 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-12T17:00:18.543831Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 1"} {"level":"info","ts":"2026-02-12T17:00:18.543915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"warn","ts":"2026-02-12T17:00:18.552486Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-12T17:00:18.566081Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-12T17:00:18.570551Z","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-12T17:00:18.576404Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"27c4b87f3ea730e3","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-12T17:00:18.581818Z","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-12T17:00:18.58253Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"27c4b87f3ea730e3","initial-advertise-peer-urls":["https://199.19.213.17:2380"],"listen-peer-urls":["https://199.19.213.17:2380"],"advertise-client-urls":["https://199.19.213.17:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.17:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-12T17:00:18.582716Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"27c4b87f3ea730e3","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-12T17:00:18.583054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"info","ts":"2026-02-12T17:00:18.585281Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","added-peer-id":"27c4b87f3ea730e3","added-peer-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-02-12T17:00:18.583095Z","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-12T17:00:18.58546Z","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-12T17:00:18.585474Z","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-12T17:00:18.583328Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-12T17:00:18.583405Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-02-12T17:00:18.586235Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-02-12T17:00:18.944187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 is starting a new election at term 1"} {"level":"info","ts":"2026-02-12T17:00:18.944274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-12T17:00:18.944305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgPreVoteResp from 27c4b87f3ea730e3 at term 1"} {"level":"info","ts":"2026-02-12T17:00:18.944321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became candidate at term 2"} {"level":"info","ts":"2026-02-12T17:00:18.944328Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgVoteResp from 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-02-12T17:00:18.94434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became leader at term 2"} {"level":"info","ts":"2026-02-12T17:00:18.944354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 27c4b87f3ea730e3 elected leader 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-02-12T17:00:18.952899Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T17:00:18.953226Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"27c4b87f3ea730e3","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.17:2379]}","request-path":"/0/members/27c4b87f3ea730e3/attributes","cluster-id":"cdce983f4a7486fe","publish-timeout":"7s"} {"level":"info","ts":"2026-02-12T17:00:18.953251Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T17:00:18.953409Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-12T17:00:18.960865Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-12T17:00:18.960981Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-12T17:00:18.961098Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"cdce983f4a7486fe","local-member-id":"27c4b87f3ea730e3","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T17:00:18.961263Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T17:00:18.961371Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-12T17:00:18.964223Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.17:2379"} {"level":"info","ts":"2026-02-12T17:00:18.965884Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-12T17:00:25.186705Z","caller":"traceutil/trace.go:171","msg":"trace[914364403] transaction","detail":"{read_only:false; response_revision:269; number_of_response:1; }","duration":"126.715798ms","start":"2026-02-12T17:00:25.05996Z","end":"2026-02-12T17:00:25.186676Z","steps":["trace[914364403] 'process raft request' (duration: 99.763606ms)","trace[914364403] 'compare' (duration: 26.529082ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:25.57672Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.087163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:00:25.57696Z","caller":"traceutil/trace.go:171","msg":"trace[1102051196] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"249.95843ms","start":"2026-02-12T17:00:25.326956Z","end":"2026-02-12T17:00:25.576915Z","steps":["trace[1102051196] 'process raft request' (duration: 114.027228ms)","trace[1102051196] 'compare' (duration: 132.825607ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:25.581046Z","caller":"traceutil/trace.go:171","msg":"trace[1893672759] linearizableReadLoop","detail":"{readStateIndex:280; appliedIndex:278; }","duration":"213.370794ms","start":"2026-02-12T17:00:25.367659Z","end":"2026-02-12T17:00:25.58103Z","steps":["trace[1893672759] 'read index received' (duration: 73.335855ms)","trace[1893672759] 'applied index is now lower than readState.Index' (duration: 140.033899ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:25.58108Z","caller":"traceutil/trace.go:171","msg":"trace[902008152] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"251.761689ms","start":"2026-02-12T17:00:25.329299Z","end":"2026-02-12T17:00:25.581061Z","steps":["trace[902008152] 'process raft request' (duration: 251.551304ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.581122Z","caller":"traceutil/trace.go:171","msg":"trace[440287285] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"212.366073ms","start":"2026-02-12T17:00:25.368749Z","end":"2026-02-12T17:00:25.581115Z","steps":["trace[440287285] 'process raft request' (duration: 212.2038ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.581328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.64764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-12T17:00:25.58135Z","caller":"traceutil/trace.go:171","msg":"trace[1529026797] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:275; }","duration":"213.728412ms","start":"2026-02-12T17:00:25.367616Z","end":"2026-02-12T17:00:25.581344Z","steps":["trace[1529026797] 'agreement among raft nodes before linearized reading' (duration: 213.64068ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.581563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.607268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:00:25.58159Z","caller":"traceutil/trace.go:171","msg":"trace[1304337589] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:276; }","duration":"211.667909ms","start":"2026-02-12T17:00:25.369912Z","end":"2026-02-12T17:00:25.58158Z","steps":["trace[1304337589] 'agreement among raft nodes before linearized reading' (duration: 211.585887ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.581613Z","caller":"traceutil/trace.go:171","msg":"trace[181362470] transaction","detail":"{read_only:false; number_of_response:0; response_revision:275; }","duration":"127.956333ms","start":"2026-02-12T17:00:25.453647Z","end":"2026-02-12T17:00:25.581603Z","steps":["trace[181362470] 'process raft request' (duration: 127.705307ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.694207Z","caller":"traceutil/trace.go:171","msg":"trace[1347697935] linearizableReadLoop","detail":"{readStateIndex:286; appliedIndex:283; }","duration":"105.436656ms","start":"2026-02-12T17:00:25.588741Z","end":"2026-02-12T17:00:25.694178Z","steps":["trace[1347697935] 'read index received' (duration: 59.035042ms)","trace[1347697935] 'applied index is now lower than readState.Index' (duration: 46.400464ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:25.694705Z","caller":"traceutil/trace.go:171","msg":"trace[1651415261] transaction","detail":"{read_only:false; response_revision:278; number_of_response:1; }","duration":"106.210312ms","start":"2026-02-12T17:00:25.588482Z","end":"2026-02-12T17:00:25.694692Z","steps":["trace[1651415261] 'process raft request' (duration: 102.758819ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.69532Z","caller":"traceutil/trace.go:171","msg":"trace[1260873233] transaction","detail":"{read_only:false; response_revision:279; number_of_response:1; }","duration":"106.59087ms","start":"2026-02-12T17:00:25.588716Z","end":"2026-02-12T17:00:25.695307Z","steps":["trace[1260873233] 'process raft request' (duration: 105.222661ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.695733Z","caller":"traceutil/trace.go:171","msg":"trace[123110834] transaction","detail":"{read_only:false; response_revision:280; number_of_response:1; }","duration":"106.866956ms","start":"2026-02-12T17:00:25.588805Z","end":"2026-02-12T17:00:25.695672Z","steps":["trace[123110834] 'process raft request' (duration: 105.171931ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.696278Z","caller":"traceutil/trace.go:171","msg":"trace[543981422] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"107.387667ms","start":"2026-02-12T17:00:25.58887Z","end":"2026-02-12T17:00:25.696258Z","steps":["trace[543981422] 'process raft request' (duration: 105.1365ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.696626Z","caller":"traceutil/trace.go:171","msg":"trace[566473146] transaction","detail":"{read_only:false; response_revision:282; number_of_response:1; }","duration":"107.725695ms","start":"2026-02-12T17:00:25.588888Z","end":"2026-02-12T17:00:25.696614Z","steps":["trace[566473146] 'process raft request' (duration: 105.207361ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:25.69699Z","caller":"traceutil/trace.go:171","msg":"trace[310233154] transaction","detail":"{read_only:false; response_revision:283; number_of_response:1; }","duration":"106.635051ms","start":"2026-02-12T17:00:25.590343Z","end":"2026-02-12T17:00:25.696979Z","steps":["trace[310233154] 'process raft request' (duration: 103.78866ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.69737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.602063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-12T17:00:25.697417Z","caller":"traceutil/trace.go:171","msg":"trace[1231120194] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"108.682685ms","start":"2026-02-12T17:00:25.588724Z","end":"2026-02-12T17:00:25.697406Z","steps":["trace[1231120194] 'agreement among raft nodes before linearized reading' (duration: 108.510821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.698775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.793488ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-02-12T17:00:25.698822Z","caller":"traceutil/trace.go:171","msg":"trace[1173870055] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:283; }","duration":"109.88446ms","start":"2026-02-12T17:00:25.588926Z","end":"2026-02-12T17:00:25.69881Z","steps":["trace[1173870055] 'agreement among raft nodes before linearized reading' (duration: 109.779458ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.700177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.922871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3704"} {"level":"info","ts":"2026-02-12T17:00:25.700243Z","caller":"traceutil/trace.go:171","msg":"trace[646806713] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:283; }","duration":"110.000232ms","start":"2026-02-12T17:00:25.590232Z","end":"2026-02-12T17:00:25.700232Z","steps":["trace[646806713] 'agreement among raft nodes before linearized reading' (duration: 109.8602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:25.700503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.141037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:00:25.700531Z","caller":"traceutil/trace.go:171","msg":"trace[1123393533] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:0; response_revision:283; }","duration":"111.183728ms","start":"2026-02-12T17:00:25.58934Z","end":"2026-02-12T17:00:25.700524Z","steps":["trace[1123393533] 'agreement among raft nodes before linearized reading' (duration: 111.119136ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:29.577321Z","caller":"traceutil/trace.go:171","msg":"trace[810631690] transaction","detail":"{read_only:false; response_revision:353; number_of_response:1; }","duration":"122.757904ms","start":"2026-02-12T17:00:29.454529Z","end":"2026-02-12T17:00:29.577286Z","steps":["trace[810631690] 'process raft request' (duration: 62.341462ms)","trace[810631690] 'compare' (duration: 60.194417ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:29.728325Z","caller":"traceutil/trace.go:171","msg":"trace[458902093] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"112.845834ms","start":"2026-02-12T17:00:29.615389Z","end":"2026-02-12T17:00:29.728235Z","steps":["trace[458902093] 'process raft request' (duration: 62.976586ms)","trace[458902093] 'compare' (duration: 49.659023ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:31.099265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.734666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3857"} {"level":"info","ts":"2026-02-12T17:00:31.099339Z","caller":"traceutil/trace.go:171","msg":"trace[933489311] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:389; }","duration":"291.841409ms","start":"2026-02-12T17:00:30.807482Z","end":"2026-02-12T17:00:31.099323Z","steps":["trace[933489311] 'range keys from in-memory index tree' (duration: 291.643584ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.099587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.592277ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:00:31.099651Z","caller":"traceutil/trace.go:171","msg":"trace[1173440274] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"311.699259ms","start":"2026-02-12T17:00:30.787936Z","end":"2026-02-12T17:00:31.099635Z","steps":["trace[1173440274] 'range keys from in-memory index tree' (duration: 311.492695ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.099684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:30.787917Z","time spent":"311.76125ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:00:31.09991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.44554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:00:31.099935Z","caller":"traceutil/trace.go:171","msg":"trace[562125292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"308.479131ms","start":"2026-02-12T17:00:30.791444Z","end":"2026-02-12T17:00:31.099923Z","steps":["trace[562125292] 'range keys from in-memory index tree' (duration: 308.41141ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.099954Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:30.791437Z","time spent":"308.512652ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T17:00:31.685179Z","caller":"traceutil/trace.go:171","msg":"trace[1522844889] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"578.560648ms","start":"2026-02-12T17:00:31.10659Z","end":"2026-02-12T17:00:31.685151Z","steps":["trace[1522844889] 'process raft request' (duration: 578.023117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.685608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:31.10657Z","time spent":"578.706481ms","remote":"127.0.0.1:58910","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-12T17:00:31.756973Z","caller":"traceutil/trace.go:171","msg":"trace[1477073845] linearizableReadLoop","detail":"{readStateIndex:399; appliedIndex:398; }","duration":"355.933177ms","start":"2026-02-12T17:00:31.401018Z","end":"2026-02-12T17:00:31.756951Z","steps":["trace[1477073845] 'read index received' (duration: 286.104596ms)","trace[1477073845] 'applied index is now lower than readState.Index' (duration: 69.827761ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:31.757132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.119931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6894"} {"level":"info","ts":"2026-02-12T17:00:31.757155Z","caller":"traceutil/trace.go:171","msg":"trace[355598126] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:393; }","duration":"356.223863ms","start":"2026-02-12T17:00:31.400925Z","end":"2026-02-12T17:00:31.757149Z","steps":["trace[355598126] 'agreement among raft nodes before linearized reading' (duration: 356.113971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.757179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:31.400901Z","time spent":"356.272185ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6917,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-02-12T17:00:31.757487Z","caller":"traceutil/trace.go:171","msg":"trace[1146406094] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"649.422271ms","start":"2026-02-12T17:00:31.108057Z","end":"2026-02-12T17:00:31.75748Z","steps":["trace[1146406094] 'process raft request' (duration: 648.711016ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.757562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:31.10804Z","time spent":"649.469473ms","remote":"127.0.0.1:58910","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-12T17:00:31.757649Z","caller":"traceutil/trace.go:171","msg":"trace[789611390] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"324.04898ms","start":"2026-02-12T17:00:31.433592Z","end":"2026-02-12T17:00:31.757641Z","steps":["trace[789611390] 'process raft request' (duration: 323.305215ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:31.757676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:31.433563Z","time spent":"324.101032ms","remote":"127.0.0.1:58716","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-12T17:00:32.258194Z","caller":"traceutil/trace.go:171","msg":"trace[2117748878] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"121.459785ms","start":"2026-02-12T17:00:32.136702Z","end":"2026-02-12T17:00:32.258162Z","steps":["trace[2117748878] 'process raft request' (duration: 121.248721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:35.931202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.192343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:00:35.931259Z","caller":"traceutil/trace.go:171","msg":"trace[1627498879] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:413; }","duration":"123.279444ms","start":"2026-02-12T17:00:35.807965Z","end":"2026-02-12T17:00:35.931244Z","steps":["trace[1627498879] 'range keys from in-memory index tree' (duration: 123.142632ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:41.944188Z","caller":"traceutil/trace.go:171","msg":"trace[435360394] linearizableReadLoop","detail":"{readStateIndex:438; appliedIndex:437; }","duration":"115.753994ms","start":"2026-02-12T17:00:41.828412Z","end":"2026-02-12T17:00:41.944166Z","steps":["trace[435360394] 'read index received' (duration: 115.580591ms)","trace[435360394] 'applied index is now lower than readState.Index' (duration: 172.523µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:41.944321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.890528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:00:41.944348Z","caller":"traceutil/trace.go:171","msg":"trace[1654968442] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"115.98546ms","start":"2026-02-12T17:00:41.828354Z","end":"2026-02-12T17:00:41.944339Z","steps":["trace[1654968442] 'agreement among raft nodes before linearized reading' (duration: 115.898647ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:41.944614Z","caller":"traceutil/trace.go:171","msg":"trace[2091887951] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"440.775337ms","start":"2026-02-12T17:00:41.503827Z","end":"2026-02-12T17:00:41.944602Z","steps":["trace[2091887951] 'process raft request' (duration: 440.224205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:41.944708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.503802Z","time spent":"440.830999ms","remote":"127.0.0.1:59094","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:00:42.386535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.46611ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:00:42.386676Z","caller":"traceutil/trace.go:171","msg":"trace[1864049006] linearizableReadLoop","detail":"{readStateIndex:441; appliedIndex:438; }","duration":"408.990722ms","start":"2026-02-12T17:00:41.977674Z","end":"2026-02-12T17:00:42.386665Z","steps":["trace[1864049006] 'read index received' (duration: 208.281807ms)","trace[1864049006] 'applied index is now lower than readState.Index' (duration: 200.707935ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:00:42.386741Z","caller":"traceutil/trace.go:171","msg":"trace[842876760] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"434.270178ms","start":"2026-02-12T17:00:41.952432Z","end":"2026-02-12T17:00:42.386702Z","steps":["trace[842876760] 'process raft request' (duration: 434.174166ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:42.386816Z","caller":"traceutil/trace.go:171","msg":"trace[1037657298] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"419.591546ms","start":"2026-02-12T17:00:41.967218Z","end":"2026-02-12T17:00:42.386809Z","steps":["trace[1037657298] 'process raft request' (duration: 419.424183ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:42.386852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.967168Z","time spent":"419.667499ms","remote":"127.0.0.1:58910","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-12T17:00:42.386889Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.952413Z","time spent":"434.405292ms","remote":"127.0.0.1:59122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3656,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T17:00:42.386953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.29385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:00:42.38697Z","caller":"traceutil/trace.go:171","msg":"trace[519022938] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:430; }","duration":"409.365192ms","start":"2026-02-12T17:00:41.9776Z","end":"2026-02-12T17:00:42.386965Z","steps":["trace[519022938] 'agreement among raft nodes before linearized reading' (duration: 409.336771ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:42.386984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.977579Z","time spent":"409.402832ms","remote":"127.0.0.1:58624","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-12T17:00:42.387085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.788748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:00:42.387098Z","caller":"traceutil/trace.go:171","msg":"trace[200207541] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:430; }","duration":"407.852879ms","start":"2026-02-12T17:00:41.979242Z","end":"2026-02-12T17:00:42.387094Z","steps":["trace[200207541] 'agreement among raft nodes before linearized reading' (duration: 407.826049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:42.38711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.979218Z","time spent":"407.88907ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-12T17:00:42.387232Z","caller":"traceutil/trace.go:171","msg":"trace[335830199] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"504.940468ms","start":"2026-02-12T17:00:41.882266Z","end":"2026-02-12T17:00:42.387206Z","steps":["trace[335830199] 'process raft request' (duration: 303.745901ms)","trace[335830199] 'compare' (duration: 200.364578ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:42.387321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:41.88223Z","time spent":"505.03883ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:00:46.556364Z","caller":"traceutil/trace.go:171","msg":"trace[2033423063] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"137.988796ms","start":"2026-02-12T17:00:46.418353Z","end":"2026-02-12T17:00:46.556342Z","steps":["trace[2033423063] 'process raft request' (duration: 137.495106ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:00:58.862465Z","caller":"traceutil/trace.go:171","msg":"trace[1837389137] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"756.852479ms","start":"2026-02-12T17:00:58.105586Z","end":"2026-02-12T17:00:58.862439Z","steps":["trace[1837389137] 'process raft request' (duration: 756.579343ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:00:58.862713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:00:58.10556Z","time spent":"757.042663ms","remote":"127.0.0.1:58814","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4210,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:00:58.877469Z","caller":"traceutil/trace.go:171","msg":"trace[799666388] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:542; }","duration":"188.129452ms","start":"2026-02-12T17:00:58.68932Z","end":"2026-02-12T17:00:58.877449Z","steps":["trace[799666388] 'read index received' (duration: 172.828705ms)","trace[799666388] 'applied index is now lower than readState.Index' (duration: 15.299637ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:00:58.877843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.5131ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:00:58.877911Z","caller":"traceutil/trace.go:171","msg":"trace[1423251753] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:529; }","duration":"188.621521ms","start":"2026-02-12T17:00:58.68928Z","end":"2026-02-12T17:00:58.877901Z","steps":["trace[1423251753] 'agreement among raft nodes before linearized reading' (duration: 188.452318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:01.482565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955497594,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:01:01.568397Z","caller":"traceutil/trace.go:171","msg":"trace[1200755868] linearizableReadLoop","detail":"{readStateIndex:549; appliedIndex:548; }","duration":"586.646873ms","start":"2026-02-12T17:01:00.98173Z","end":"2026-02-12T17:01:01.568376Z","steps":["trace[1200755868] 'read index received' (duration: 586.478459ms)","trace[1200755868] 'applied index is now lower than readState.Index' (duration: 167.584µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:01.568682Z","caller":"traceutil/trace.go:171","msg":"trace[1681219287] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"667.199585ms","start":"2026-02-12T17:01:00.901451Z","end":"2026-02-12T17:01:01.568651Z","steps":["trace[1681219287] 'process raft request' (duration: 666.808237ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:01.568773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:00.901441Z","time spent":"667.266017ms","remote":"127.0.0.1:58910","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-12T17:01:01.568955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.224365ms","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-12T17:01:01.568977Z","caller":"traceutil/trace.go:171","msg":"trace[9981275] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:534; }","duration":"587.269417ms","start":"2026-02-12T17:01:00.9817Z","end":"2026-02-12T17:01:01.56897Z","steps":["trace[9981275] 'agreement among raft nodes before linearized reading' (duration: 587.188205ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:01.568996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:00.981683Z","time spent":"587.308227ms","remote":"127.0.0.1:58910","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-12T17:01:01.569109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.209915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:01:01.569129Z","caller":"traceutil/trace.go:171","msg":"trace[1638624334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:534; }","duration":"577.238925ms","start":"2026-02-12T17:01:00.991884Z","end":"2026-02-12T17:01:01.569123Z","steps":["trace[1638624334] 'agreement among raft nodes before linearized reading' (duration: 577.187124ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:01.569146Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:00.991877Z","time spent":"577.265126ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:01:01.56928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.354511ms","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-12T17:01:01.569303Z","caller":"traceutil/trace.go:171","msg":"trace[866986040] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:534; }","duration":"539.400483ms","start":"2026-02-12T17:01:01.029896Z","end":"2026-02-12T17:01:01.569297Z","steps":["trace[866986040] 'agreement among raft nodes before linearized reading' (duration: 539.342892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:01.569322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:01.029881Z","time spent":"539.434623ms","remote":"127.0.0.1:58910","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-12T17:01:01.824041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.889837ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:01.824225Z","caller":"traceutil/trace.go:171","msg":"trace[725676129] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"246.249731ms","start":"2026-02-12T17:01:01.577959Z","end":"2026-02-12T17:01:01.824208Z","steps":["trace[725676129] 'process raft request' (duration: 246.21802ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:01.824484Z","caller":"traceutil/trace.go:171","msg":"trace[728390851] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"247.559049ms","start":"2026-02-12T17:01:01.576917Z","end":"2026-02-12T17:01:01.824476Z","steps":["trace[728390851] 'process raft request' (duration: 118.14656ms)","trace[728390851] 'compare' (duration: 128.744974ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:01.824577Z","caller":"traceutil/trace.go:171","msg":"trace[1020110092] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"246.774142ms","start":"2026-02-12T17:01:01.577798Z","end":"2026-02-12T17:01:01.824572Z","steps":["trace[1020110092] 'process raft request' (duration: 246.343052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:02.392891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"442.610823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:02.392962Z","caller":"traceutil/trace.go:171","msg":"trace[58149963] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:537; }","duration":"442.717694ms","start":"2026-02-12T17:01:01.950226Z","end":"2026-02-12T17:01:02.392944Z","steps":["trace[58149963] 'range keys from in-memory index tree' (duration: 442.554561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:02.393004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:01.950209Z","time spent":"442.785976ms","remote":"127.0.0.1:58624","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-12T17:01:03.118855Z","caller":"traceutil/trace.go:171","msg":"trace[1781620202] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:552; }","duration":"164.344788ms","start":"2026-02-12T17:01:02.954487Z","end":"2026-02-12T17:01:03.118831Z","steps":["trace[1781620202] 'read index received' (duration: 164.152984ms)","trace[1781620202] 'applied index is now lower than readState.Index' (duration: 190.974µs)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:03.119027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.537222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:03.119062Z","caller":"traceutil/trace.go:171","msg":"trace[809709196] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:538; }","duration":"164.621904ms","start":"2026-02-12T17:01:02.95443Z","end":"2026-02-12T17:01:03.119052Z","steps":["trace[809709196] 'agreement among raft nodes before linearized reading' (duration: 164.520532ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:03.119418Z","caller":"traceutil/trace.go:171","msg":"trace[147190419] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"277.313073ms","start":"2026-02-12T17:01:02.842094Z","end":"2026-02-12T17:01:03.119407Z","steps":["trace[147190419] 'process raft request' (duration: 276.610138ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:03.936635Z","caller":"traceutil/trace.go:171","msg":"trace[1748710373] linearizableReadLoop","detail":"{readStateIndex:558; appliedIndex:557; }","duration":"103.236263ms","start":"2026-02-12T17:01:03.833374Z","end":"2026-02-12T17:01:03.93661Z","steps":["trace[1748710373] 'read index received' (duration: 48.57906ms)","trace[1748710373] 'applied index is now lower than readState.Index' (duration: 54.655533ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:03.936839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.441477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:01:03.937958Z","caller":"traceutil/trace.go:171","msg":"trace[174285327] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:542; }","duration":"104.574533ms","start":"2026-02-12T17:01:03.833363Z","end":"2026-02-12T17:01:03.937937Z","steps":["trace[174285327] 'agreement among raft nodes before linearized reading' (duration: 103.359165ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:03.936905Z","caller":"traceutil/trace.go:171","msg":"trace[2023951257] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"104.290796ms","start":"2026-02-12T17:01:03.832596Z","end":"2026-02-12T17:01:03.936887Z","steps":["trace[2023951257] 'process raft request' (duration: 103.037509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.424284Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955497634,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:01:08.617099Z","caller":"traceutil/trace.go:171","msg":"trace[1397847905] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:564; }","duration":"693.176007ms","start":"2026-02-12T17:01:07.923898Z","end":"2026-02-12T17:01:08.617074Z","steps":["trace[1397847905] 'read index received' (duration: 692.950963ms)","trace[1397847905] 'applied index is now lower than readState.Index' (duration: 223.904µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:08.617454Z","caller":"traceutil/trace.go:171","msg":"trace[307029095] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"987.141907ms","start":"2026-02-12T17:01:07.630297Z","end":"2026-02-12T17:01:08.617439Z","steps":["trace[307029095] 'process raft request' (duration: 986.625696ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.617583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:07.630287Z","time spent":"987.203338ms","remote":"127.0.0.1:58910","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-12T17:01:08.617914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"694.011315ms","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-12T17:01:08.61797Z","caller":"traceutil/trace.go:171","msg":"trace[1934564395] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:549; }","duration":"694.098967ms","start":"2026-02-12T17:01:07.923854Z","end":"2026-02-12T17:01:08.617953Z","steps":["trace[1934564395] 'agreement among raft nodes before linearized reading' (duration: 693.950144ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.618026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:07.923835Z","time spent":"694.173639ms","remote":"127.0.0.1:58910","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-12T17:01:08.61823Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"665.813364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:08.618272Z","caller":"traceutil/trace.go:171","msg":"trace[643155543] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:549; }","duration":"665.876695ms","start":"2026-02-12T17:01:07.952383Z","end":"2026-02-12T17:01:08.61826Z","steps":["trace[643155543] 'agreement among raft nodes before linearized reading' (duration: 665.812104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.618305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:07.952372Z","time spent":"665.926007ms","remote":"127.0.0.1:58624","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-12T17:01:08.61857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.111323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:01:08.618611Z","caller":"traceutil/trace.go:171","msg":"trace[1046735221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:549; }","duration":"637.166304ms","start":"2026-02-12T17:01:07.981435Z","end":"2026-02-12T17:01:08.618601Z","steps":["trace[1046735221] 'agreement among raft nodes before linearized reading' (duration: 637.064721ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.618648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:07.98142Z","time spent":"637.217395ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:01:08.618811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.463459ms","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-12T17:01:08.618836Z","caller":"traceutil/trace.go:171","msg":"trace[688384808] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:549; }","duration":"646.524139ms","start":"2026-02-12T17:01:07.972304Z","end":"2026-02-12T17:01:08.618828Z","steps":["trace[688384808] 'agreement among raft nodes before linearized reading' (duration: 646.445028ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:08.618863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:07.972293Z","time spent":"646.561601ms","remote":"127.0.0.1:58910","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":"info","ts":"2026-02-12T17:01:09.003755Z","caller":"traceutil/trace.go:171","msg":"trace[609001852] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"370.418663ms","start":"2026-02-12T17:01:08.63331Z","end":"2026-02-12T17:01:09.003729Z","steps":["trace[609001852] 'process raft request' (duration: 370.319701ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:09.00391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:08.633306Z","time spent":"370.526185ms","remote":"127.0.0.1:58910","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-12T17:01:09.003973Z","caller":"traceutil/trace.go:171","msg":"trace[531283185] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"368.909761ms","start":"2026-02-12T17:01:08.635039Z","end":"2026-02-12T17:01:09.003948Z","steps":["trace[531283185] 'process raft request' (duration: 368.650595ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:09.004121Z","caller":"traceutil/trace.go:171","msg":"trace[1781516174] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"370.835702ms","start":"2026-02-12T17:01:08.633274Z","end":"2026-02-12T17:01:09.00411Z","steps":["trace[1781516174] 'process raft request' (duration: 370.229479ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:09.004184Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:08.635032Z","time spent":"369.019523ms","remote":"127.0.0.1:58910","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-12T17:01:09.00419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:08.633263Z","time spent":"370.884883ms","remote":"127.0.0.1:58910","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-12T17:01:11.090957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.712582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:11.091016Z","caller":"traceutil/trace.go:171","msg":"trace[1429550497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:560; }","duration":"140.719207ms","start":"2026-02-12T17:01:10.950284Z","end":"2026-02-12T17:01:11.091003Z","steps":["trace[1429550497] 'range keys from in-memory index tree' (duration: 138.642441ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:15.120417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.742334ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:15.122281Z","caller":"traceutil/trace.go:171","msg":"trace[1859755355] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"257.014528ms","start":"2026-02-12T17:01:14.865232Z","end":"2026-02-12T17:01:15.122246Z","steps":["trace[1859755355] 'process raft request' (duration: 61.260159ms)","trace[1859755355] 'compare' (duration: 193.464488ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:15.13179Z","caller":"traceutil/trace.go:171","msg":"trace[1723203456] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"114.466609ms","start":"2026-02-12T17:01:15.017297Z","end":"2026-02-12T17:01:15.131764Z","steps":["trace[1723203456] 'process raft request' (duration: 114.262074ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:21.45206Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955497885,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:01:21.565161Z","caller":"traceutil/trace.go:171","msg":"trace[439203057] transaction","detail":"{read_only:false; response_revision:674; number_of_response:1; }","duration":"860.916818ms","start":"2026-02-12T17:01:20.704224Z","end":"2026-02-12T17:01:21.565141Z","steps":["trace[439203057] 'process raft request' (duration: 860.787535ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:21.565299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:20.704209Z","time spent":"861.003981ms","remote":"127.0.0.1:58910","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-12T17:01:22.75224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"537.159913ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:22.752318Z","caller":"traceutil/trace.go:171","msg":"trace[137388754] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:693; }","duration":"1.800349847s","start":"2026-02-12T17:01:20.951957Z","end":"2026-02-12T17:01:22.752307Z","steps":["trace[137388754] 'read index received' (duration: 613.154166ms)","trace[137388754] 'applied index is now lower than readState.Index' (duration: 1.187195001s)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:22.752423Z","caller":"traceutil/trace.go:171","msg":"trace[867691263] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"1.891046422s","start":"2026-02-12T17:01:20.861368Z","end":"2026-02-12T17:01:22.752415Z","steps":["trace[867691263] 'process raft request' (duration: 1.353647784s)","trace[867691263] 'compare' (duration: 536.752283ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:22.752467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:20.861337Z","time spent":"1.891117123s","remote":"127.0.0.1:60026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2261,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-12T17:01:22.752615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.800662933s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:22.75263Z","caller":"traceutil/trace.go:171","msg":"trace[566302745] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:675; }","duration":"1.800710204s","start":"2026-02-12T17:01:20.951914Z","end":"2026-02-12T17:01:22.752625Z","steps":["trace[566302745] 'agreement among raft nodes before linearized reading' (duration: 1.800655003s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.752644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:20.951894Z","time spent":"1.800747495s","remote":"127.0.0.1:58624","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-12T17:01:22.752835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.574770821s","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-12T17:01:22.752851Z","caller":"traceutil/trace.go:171","msg":"trace[1456306817] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:675; }","duration":"1.574822452s","start":"2026-02-12T17:01:21.178023Z","end":"2026-02-12T17:01:22.752845Z","steps":["trace[1456306817] 'agreement among raft nodes before linearized reading' (duration: 1.574751771s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.752862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:21.177976Z","time spent":"1.574883004s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T17:01:22.753018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.91647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-zfht6\" ","response":"range_response_count:1 size:18246"} {"level":"info","ts":"2026-02-12T17:01:22.753031Z","caller":"traceutil/trace.go:171","msg":"trace[1053865567] range","detail":"{range_begin:/registry/pods/kube-system/cilium-zfht6; range_end:; response_count:1; response_revision:675; }","duration":"960.93005ms","start":"2026-02-12T17:01:21.792097Z","end":"2026-02-12T17:01:22.753027Z","steps":["trace[1053865567] 'agreement among raft nodes before linearized reading' (duration: 960.875899ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.753042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:21.792054Z","time spent":"960.985231ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18269,"request content":"key:\"/registry/pods/kube-system/cilium-zfht6\" "} {"level":"warn","ts":"2026-02-12T17:01:22.753159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.536646272s","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-12T17:01:22.753171Z","caller":"traceutil/trace.go:171","msg":"trace[1680873294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"1.536684663s","start":"2026-02-12T17:01:21.216483Z","end":"2026-02-12T17:01:22.753167Z","steps":["trace[1680873294] 'agreement among raft nodes before linearized reading' (duration: 1.536639162s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.753185Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:21.216464Z","time spent":"1.536716234s","remote":"127.0.0.1:58910","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-12T17:01:22.753275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.562974231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-12T17:01:22.753288Z","caller":"traceutil/trace.go:171","msg":"trace[1562508763] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:675; }","duration":"1.563003162s","start":"2026-02-12T17:01:21.19028Z","end":"2026-02-12T17:01:22.753284Z","steps":["trace[1562508763] 'agreement among raft nodes before linearized reading' (duration: 1.562954021s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.753299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:21.190271Z","time spent":"1.563025462s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-12T17:01:22.753391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.569489805s","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-12T17:01:22.753404Z","caller":"traceutil/trace.go:171","msg":"trace[133023262] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:675; }","duration":"1.569517215s","start":"2026-02-12T17:01:21.183883Z","end":"2026-02-12T17:01:22.7534Z","steps":["trace[133023262] 'agreement among raft nodes before linearized reading' (duration: 1.569479394s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:22.753415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:21.183859Z","time spent":"1.569553976s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-12T17:01:23.100515Z","caller":"traceutil/trace.go:171","msg":"trace[1044060963] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"330.873463ms","start":"2026-02-12T17:01:22.769619Z","end":"2026-02-12T17:01:23.100492Z","steps":["trace[1044060963] 'process raft request' (duration: 330.28097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.100749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.769597Z","time spent":"331.006287ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:01:23.103879Z","caller":"traceutil/trace.go:171","msg":"trace[1308257136] linearizableReadLoop","detail":"{readStateIndex:697; appliedIndex:695; }","duration":"325.213159ms","start":"2026-02-12T17:01:22.778653Z","end":"2026-02-12T17:01:23.103866Z","steps":["trace[1308257136] 'read index received' (duration: 321.293923ms)","trace[1308257136] 'applied index is now lower than readState.Index' (duration: 3.918406ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:23.104036Z","caller":"traceutil/trace.go:171","msg":"trace[1913213824] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"334.181085ms","start":"2026-02-12T17:01:22.769847Z","end":"2026-02-12T17:01:23.104028Z","steps":["trace[1913213824] 'process raft request' (duration: 333.791017ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.104194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.769839Z","time spent":"334.253808ms","remote":"127.0.0.1:58910","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-12T17:01:23.104796Z","caller":"traceutil/trace.go:171","msg":"trace[1982009913] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"315.26786ms","start":"2026-02-12T17:01:22.789471Z","end":"2026-02-12T17:01:23.104739Z","steps":["trace[1982009913] 'process raft request' (duration: 314.276908ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.104843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.789448Z","time spent":"315.368122ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18231,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:01:23.105025Z","caller":"traceutil/trace.go:171","msg":"trace[1750272720] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"308.222475ms","start":"2026-02-12T17:01:22.796794Z","end":"2026-02-12T17:01:23.105016Z","steps":["trace[1750272720] 'process raft request' (duration: 307.010378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.105079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.796639Z","time spent":"308.40553ms","remote":"127.0.0.1:58910","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-12T17:01:23.105239Z","caller":"traceutil/trace.go:171","msg":"trace[1605513523] transaction","detail":"{read_only:false; response_revision:680; number_of_response:1; }","duration":"306.587589ms","start":"2026-02-12T17:01:22.798642Z","end":"2026-02-12T17:01:23.10523Z","steps":["trace[1605513523] 'process raft request' (duration: 305.188998ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.105299Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.79862Z","time spent":"306.637361ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:01:23.105484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.828645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:23.105521Z","caller":"traceutil/trace.go:171","msg":"trace[1880945798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:680; }","duration":"326.884606ms","start":"2026-02-12T17:01:22.778618Z","end":"2026-02-12T17:01:23.105502Z","steps":["trace[1880945798] 'agreement among raft nodes before linearized reading' (duration: 326.838225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.105543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:22.778595Z","time spent":"326.943727ms","remote":"127.0.0.1:58624","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-12T17:01:23.10574Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.566024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T17:01:23.105772Z","caller":"traceutil/trace.go:171","msg":"trace[559918254] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:680; }","duration":"219.622135ms","start":"2026-02-12T17:01:22.886132Z","end":"2026-02-12T17:01:23.105755Z","steps":["trace[559918254] 'agreement among raft nodes before linearized reading' (duration: 219.576514ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.804896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.496993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T17:01:23.804956Z","caller":"traceutil/trace.go:171","msg":"trace[848718844] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:684; }","duration":"299.589614ms","start":"2026-02-12T17:01:23.505355Z","end":"2026-02-12T17:01:23.804944Z","steps":["trace[848718844] 'range keys from in-memory index tree' (duration: 299.364578ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:23.805095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.395361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:01:23.805109Z","caller":"traceutil/trace.go:171","msg":"trace[95785579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:684; }","duration":"235.410822ms","start":"2026-02-12T17:01:23.569695Z","end":"2026-02-12T17:01:23.805106Z","steps":["trace[95785579] 'range keys from in-memory index tree' (duration: 235.298359ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:23.805436Z","caller":"traceutil/trace.go:171","msg":"trace[1800357798] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"233.0156ms","start":"2026-02-12T17:01:23.572414Z","end":"2026-02-12T17:01:23.805429Z","steps":["trace[1800357798] 'process raft request' (duration: 181.890983ms)","trace[1800357798] 'compare' (duration: 51.058005ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:23.95168Z","caller":"traceutil/trace.go:171","msg":"trace[2036022281] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"141.653959ms","start":"2026-02-12T17:01:23.810004Z","end":"2026-02-12T17:01:23.951658Z","steps":["trace[2036022281] 'process raft request' (duration: 138.787816ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.652031Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955497995,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:01:29.873006Z","caller":"traceutil/trace.go:171","msg":"trace[600092836] linearizableReadLoop","detail":"{readStateIndex:761; appliedIndex:760; }","duration":"721.272387ms","start":"2026-02-12T17:01:29.151714Z","end":"2026-02-12T17:01:29.872987Z","steps":["trace[600092836] 'read index received' (duration: 721.00654ms)","trace[600092836] 'applied index is now lower than readState.Index' (duration: 265.147µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:29.873265Z","caller":"traceutil/trace.go:171","msg":"trace[1091358622] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"724.109839ms","start":"2026-02-12T17:01:29.149146Z","end":"2026-02-12T17:01:29.873256Z","steps":["trace[1091358622] 'process raft request' (duration: 723.622587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.873362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.149131Z","time spent":"724.163411ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:01:29.873543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"721.824609ms","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-12T17:01:29.873573Z","caller":"traceutil/trace.go:171","msg":"trace[1882428348] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:738; }","duration":"721.86689ms","start":"2026-02-12T17:01:29.151699Z","end":"2026-02-12T17:01:29.873566Z","steps":["trace[1882428348] 'agreement among raft nodes before linearized reading' (duration: 721.778198ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.873611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.151692Z","time spent":"721.91379ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T17:01:29.873753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.810262ms","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-12T17:01:29.873777Z","caller":"traceutil/trace.go:171","msg":"trace[1738098533] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:738; }","duration":"718.857913ms","start":"2026-02-12T17:01:29.154912Z","end":"2026-02-12T17:01:29.87377Z","steps":["trace[1738098533] 'agreement among raft nodes before linearized reading' (duration: 718.789982ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.873798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.154905Z","time spent":"718.888415ms","remote":"127.0.0.1:58910","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-12T17:01:29.873945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.312844ms","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-12T17:01:29.87397Z","caller":"traceutil/trace.go:171","msg":"trace[1369774808] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:738; }","duration":"386.773163ms","start":"2026-02-12T17:01:29.48719Z","end":"2026-02-12T17:01:29.873963Z","steps":["trace[1369774808] 'agreement among raft nodes before linearized reading' (duration: 386.732892ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.873992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.487175Z","time spent":"386.811404ms","remote":"127.0.0.1:56950","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-12T17:01:29.874099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.488587ms","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-12T17:01:29.874121Z","caller":"traceutil/trace.go:171","msg":"trace[212744024] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:738; }","duration":"386.569139ms","start":"2026-02-12T17:01:29.487546Z","end":"2026-02-12T17:01:29.874115Z","steps":["trace[212744024] 'agreement among raft nodes before linearized reading' (duration: 386.536008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.874144Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.487538Z","time spent":"386.598369ms","remote":"127.0.0.1:56950","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-12T17:01:29.874235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.856225ms","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-12T17:01:29.874257Z","caller":"traceutil/trace.go:171","msg":"trace[829722530] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:738; }","duration":"386.902096ms","start":"2026-02-12T17:01:29.48735Z","end":"2026-02-12T17:01:29.874252Z","steps":["trace[829722530] 'agreement among raft nodes before linearized reading' (duration: 386.867236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.874279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.487342Z","time spent":"386.930507ms","remote":"127.0.0.1:56950","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-12T17:01:29.874379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.662595ms","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-12T17:01:29.874402Z","caller":"traceutil/trace.go:171","msg":"trace[407765295] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:738; }","duration":"403.707966ms","start":"2026-02-12T17:01:29.470688Z","end":"2026-02-12T17:01:29.874396Z","steps":["trace[407765295] 'agreement among raft nodes before linearized reading' (duration: 403.672645ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.874423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.47067Z","time spent":"403.748047ms","remote":"127.0.0.1:56948","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":"warn","ts":"2026-02-12T17:01:29.875476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.786749ms","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-12T17:01:29.875527Z","caller":"traceutil/trace.go:171","msg":"trace[2073635216] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:738; }","duration":"404.825011ms","start":"2026-02-12T17:01:29.470675Z","end":"2026-02-12T17:01:29.8755Z","steps":["trace[2073635216] 'agreement among raft nodes before linearized reading' (duration: 404.774499ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.875555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.470657Z","time spent":"404.891052ms","remote":"127.0.0.1:56948","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-12T17:01:29.875721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.074765ms","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-12T17:01:29.875748Z","caller":"traceutil/trace.go:171","msg":"trace[1940749546] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:738; }","duration":"405.124667ms","start":"2026-02-12T17:01:29.470617Z","end":"2026-02-12T17:01:29.875741Z","steps":["trace[1940749546] 'agreement among raft nodes before linearized reading' (duration: 405.082416ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.875789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.470601Z","time spent":"405.181959ms","remote":"127.0.0.1:56948","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-12T17:01:29.875961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.478446ms","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-12T17:01:29.875987Z","caller":"traceutil/trace.go:171","msg":"trace[547465688] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:738; }","duration":"420.511967ms","start":"2026-02-12T17:01:29.455468Z","end":"2026-02-12T17:01:29.87598Z","steps":["trace[547465688] 'agreement among raft nodes before linearized reading' (duration: 420.469106ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.876026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.455466Z","time spent":"420.552328ms","remote":"127.0.0.1:56946","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-12T17:01:29.876204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.731892ms","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-12T17:01:29.876233Z","caller":"traceutil/trace.go:171","msg":"trace[974666672] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:738; }","duration":"420.778102ms","start":"2026-02-12T17:01:29.455447Z","end":"2026-02-12T17:01:29.876225Z","steps":["trace[974666672] 'agreement among raft nodes before linearized reading' (duration: 420.732491ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.876326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.455433Z","time spent":"420.883605ms","remote":"127.0.0.1:56946","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-12T17:01:29.876465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.040488ms","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-12T17:01:29.87649Z","caller":"traceutil/trace.go:171","msg":"trace[1551171695] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:738; }","duration":"421.066549ms","start":"2026-02-12T17:01:29.455417Z","end":"2026-02-12T17:01:29.876484Z","steps":["trace[1551171695] 'agreement among raft nodes before linearized reading' (duration: 421.028188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:29.876614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.455403Z","time spent":"421.201011ms","remote":"127.0.0.1:56946","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":"info","ts":"2026-02-12T17:01:30.030756Z","caller":"traceutil/trace.go:171","msg":"trace[1661916368] transaction","detail":"{read_only:false; response_revision:739; number_of_response:1; }","duration":"145.342738ms","start":"2026-02-12T17:01:29.885385Z","end":"2026-02-12T17:01:30.030728Z","steps":["trace[1661916368] 'process raft request' (duration: 145.145943ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:30.030759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"874.922187ms","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-12T17:01:30.031045Z","caller":"traceutil/trace.go:171","msg":"trace[1190973556] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:738; }","duration":"875.231023ms","start":"2026-02-12T17:01:29.155797Z","end":"2026-02-12T17:01:30.031028Z","steps":["trace[1190973556] 'agreement among raft nodes before linearized reading' (duration: 721.00131ms)","trace[1190973556] 'range keys from in-memory index tree' (duration: 153.832505ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:30.031086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:29.15579Z","time spent":"875.286965ms","remote":"127.0.0.1:58910","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-12T17:01:30.03429Z","caller":"traceutil/trace.go:171","msg":"trace[1823268152] transaction","detail":"{read_only:false; response_revision:740; number_of_response:1; }","duration":"148.642771ms","start":"2026-02-12T17:01:29.885637Z","end":"2026-02-12T17:01:30.034279Z","steps":["trace[1823268152] 'process raft request' (duration: 148.260702ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.45175Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498007,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:01:32.683013Z","caller":"traceutil/trace.go:171","msg":"trace[1870380120] linearizableReadLoop","detail":"{readStateIndex:766; appliedIndex:765; }","duration":"731.994013ms","start":"2026-02-12T17:01:31.950992Z","end":"2026-02-12T17:01:32.682986Z","steps":["trace[1870380120] 'read index received' (duration: 731.83857ms)","trace[1870380120] 'applied index is now lower than readState.Index' (duration: 154.333µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:32.683142Z","caller":"traceutil/trace.go:171","msg":"trace[291785937] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"793.64492ms","start":"2026-02-12T17:01:31.889487Z","end":"2026-02-12T17:01:32.683132Z","steps":["trace[291785937] 'process raft request' (duration: 793.356824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.683254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:31.889471Z","time spent":"793.701211ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:01:32.683373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"724.567929ms","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-12T17:01:32.68344Z","caller":"traceutil/trace.go:171","msg":"trace[1674252264] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:743; }","duration":"724.661321ms","start":"2026-02-12T17:01:31.958762Z","end":"2026-02-12T17:01:32.683423Z","steps":["trace[1674252264] 'agreement among raft nodes before linearized reading' (duration: 724.547519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.683523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:31.958755Z","time spent":"724.758703ms","remote":"127.0.0.1:58928","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-12T17:01:32.683709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"603.816311ms","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-12T17:01:32.683773Z","caller":"traceutil/trace.go:171","msg":"trace[224018096] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:743; }","duration":"603.879263ms","start":"2026-02-12T17:01:32.079882Z","end":"2026-02-12T17:01:32.683761Z","steps":["trace[224018096] 'agreement among raft nodes before linearized reading' (duration: 603.73359ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.683793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"641.277915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-12T17:01:32.683805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.079842Z","time spent":"603.955355ms","remote":"127.0.0.1:58910","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-12T17:01:32.683828Z","caller":"traceutil/trace.go:171","msg":"trace[1818337651] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:743; }","duration":"641.329467ms","start":"2026-02-12T17:01:32.042488Z","end":"2026-02-12T17:01:32.683818Z","steps":["trace[1818337651] 'agreement among raft nodes before linearized reading' (duration: 641.216815ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.683856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.042479Z","time spent":"641.370628ms","remote":"127.0.0.1:58910","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-12T17:01:32.683945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.956554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:32.683972Z","caller":"traceutil/trace.go:171","msg":"trace[385324880] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:743; }","duration":"733.009035ms","start":"2026-02-12T17:01:31.950954Z","end":"2026-02-12T17:01:32.683963Z","steps":["trace[385324880] 'agreement among raft nodes before linearized reading' (duration: 732.963404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.683997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:31.950933Z","time spent":"733.059086ms","remote":"127.0.0.1:58624","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-12T17:01:32.684069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"646.269195ms","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-12T17:01:32.684116Z","caller":"traceutil/trace.go:171","msg":"trace[1149629362] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:743; }","duration":"646.325447ms","start":"2026-02-12T17:01:32.037779Z","end":"2026-02-12T17:01:32.684104Z","steps":["trace[1149629362] 'agreement among raft nodes before linearized reading' (duration: 646.195324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.684141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.037772Z","time spent":"646.363298ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T17:01:32.684152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.176844ms","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-12T17:01:32.684181Z","caller":"traceutil/trace.go:171","msg":"trace[2047047511] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:743; }","duration":"268.243875ms","start":"2026-02-12T17:01:32.415928Z","end":"2026-02-12T17:01:32.684172Z","steps":["trace[2047047511] 'agreement among raft nodes before linearized reading' (duration: 268.197394ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.6843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.4391ms","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-12T17:01:32.684331Z","caller":"traceutil/trace.go:171","msg":"trace[634916889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"647.496532ms","start":"2026-02-12T17:01:32.036826Z","end":"2026-02-12T17:01:32.684322Z","steps":["trace[634916889] 'agreement among raft nodes before linearized reading' (duration: 647.41686ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:32.684356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.036808Z","time spent":"647.542323ms","remote":"127.0.0.1:58910","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-12T17:01:33.683932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.302239ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:33.684023Z","caller":"traceutil/trace.go:171","msg":"trace[2131776231] transaction","detail":"{read_only:false; response_revision:744; number_of_response:1; }","duration":"991.950014ms","start":"2026-02-12T17:01:32.69205Z","end":"2026-02-12T17:01:33.684Z","steps":["trace[2131776231] 'process raft request' (duration: 572.501112ms)","trace[2131776231] 'compare' (duration: 419.163396ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:33.684083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.692006Z","time spent":"992.055916ms","remote":"127.0.0.1:58910","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-12T17:01:34.140196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.346524ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:34.140409Z","caller":"traceutil/trace.go:171","msg":"trace[323668078] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:766; }","duration":"1.110926013s","start":"2026-02-12T17:01:33.029465Z","end":"2026-02-12T17:01:34.140391Z","steps":["trace[323668078] 'read index received' (duration: 235.097555ms)","trace[323668078] 'applied index is now lower than readState.Index' (duration: 875.827248ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:34.140614Z","caller":"traceutil/trace.go:171","msg":"trace[1392198513] transaction","detail":"{read_only:false; response_revision:745; number_of_response:1; }","duration":"1.446513789s","start":"2026-02-12T17:01:32.694086Z","end":"2026-02-12T17:01:34.140599Z","steps":["trace[1392198513] 'process raft request' (duration: 1.278645614s)","trace[1392198513] 'compare' (duration: 167.111539ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:34.140711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.694079Z","time spent":"1.44656352s","remote":"127.0.0.1:58910","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-12T17:01:34.140892Z","caller":"traceutil/trace.go:171","msg":"trace[78825817] transaction","detail":"{read_only:false; response_revision:746; number_of_response:1; }","duration":"1.444931704s","start":"2026-02-12T17:01:32.69595Z","end":"2026-02-12T17:01:34.140882Z","steps":["trace[78825817] 'process raft request' (duration: 1.444349952s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:34.140941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.695943Z","time spent":"1.444969935s","remote":"127.0.0.1:58910","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-12T17:01:34.141059Z","caller":"traceutil/trace.go:171","msg":"trace[1798065227] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"1.444863113s","start":"2026-02-12T17:01:32.696188Z","end":"2026-02-12T17:01:34.141051Z","steps":["trace[1798065227] 'process raft request' (duration: 1.444165538s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:34.141103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:32.696164Z","time spent":"1.444915285s","remote":"127.0.0.1:58910","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-12T17:01:34.141237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.111769731s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:34.141265Z","caller":"traceutil/trace.go:171","msg":"trace[203557471] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:747; }","duration":"1.111795052s","start":"2026-02-12T17:01:33.029459Z","end":"2026-02-12T17:01:34.141254Z","steps":["trace[203557471] 'agreement among raft nodes before linearized reading' (duration: 1.111751591s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:34.14129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:33.029409Z","time spent":"1.111876014s","remote":"127.0.0.1:58624","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-12T17:01:34.208429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.585545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T17:01:34.208483Z","caller":"traceutil/trace.go:171","msg":"trace[628922273] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:748; }","duration":"702.682936ms","start":"2026-02-12T17:01:33.505788Z","end":"2026-02-12T17:01:34.208471Z","steps":["trace[628922273] 'agreement among raft nodes before linearized reading' (duration: 702.531813ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:34.208516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:33.505766Z","time spent":"702.741038ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.17\" "} {"level":"warn","ts":"2026-02-12T17:01:34.20864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.029285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:34.208684Z","caller":"traceutil/trace.go:171","msg":"trace[161601890] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"260.064675ms","start":"2026-02-12T17:01:33.948588Z","end":"2026-02-12T17:01:34.208653Z","steps":["trace[161601890] 'agreement among raft nodes before linearized reading' (duration: 260.036275ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:34.208784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.190378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T17:01:34.208798Z","caller":"traceutil/trace.go:171","msg":"trace[1402025046] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:748; }","duration":"280.209568ms","start":"2026-02-12T17:01:33.928584Z","end":"2026-02-12T17:01:34.208793Z","steps":["trace[1402025046] 'agreement among raft nodes before linearized reading' (duration: 280.176968ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:35.493098Z","caller":"traceutil/trace.go:171","msg":"trace[960015108] transaction","detail":"{read_only:false; response_revision:753; number_of_response:1; }","duration":"144.510101ms","start":"2026-02-12T17:01:35.348567Z","end":"2026-02-12T17:01:35.493077Z","steps":["trace[960015108] 'process raft request' (duration: 138.196681ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:36.290303Z","caller":"traceutil/trace.go:171","msg":"trace[1035018470] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"103.851655ms","start":"2026-02-12T17:01:36.186433Z","end":"2026-02-12T17:01:36.290284Z","steps":["trace[1035018470] 'process raft request' (duration: 103.740773ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:36.345454Z","caller":"traceutil/trace.go:171","msg":"trace[1265830424] transaction","detail":"{read_only:false; response_revision:759; number_of_response:1; }","duration":"157.237601ms","start":"2026-02-12T17:01:36.188197Z","end":"2026-02-12T17:01:36.345435Z","steps":["trace[1265830424] 'process raft request' (duration: 157.20884ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:36.345767Z","caller":"traceutil/trace.go:171","msg":"trace[1177248328] transaction","detail":"{read_only:false; response_revision:758; number_of_response:1; }","duration":"158.870828ms","start":"2026-02-12T17:01:36.18689Z","end":"2026-02-12T17:01:36.345761Z","steps":["trace[1177248328] 'process raft request' (duration: 132.929297ms)","trace[1177248328] 'compare' (duration: 25.497911ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:38.243388Z","caller":"traceutil/trace.go:171","msg":"trace[151144457] transaction","detail":"{read_only:false; response_revision:762; number_of_response:1; }","duration":"507.154552ms","start":"2026-02-12T17:01:37.7362Z","end":"2026-02-12T17:01:38.243355Z","steps":["trace[151144457] 'process raft request' (duration: 507.034539ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:38.24354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:37.736186Z","time spent":"507.264065ms","remote":"127.0.0.1:58910","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-12T17:01:38.405285Z","caller":"traceutil/trace.go:171","msg":"trace[1939113393] transaction","detail":"{read_only:false; response_revision:763; number_of_response:1; }","duration":"105.085712ms","start":"2026-02-12T17:01:38.300179Z","end":"2026-02-12T17:01:38.405264Z","steps":["trace[1939113393] 'process raft request' (duration: 99.107731ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:38.87372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.760533ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:38.873787Z","caller":"traceutil/trace.go:171","msg":"trace[77515102] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"387.468399ms","start":"2026-02-12T17:01:38.486309Z","end":"2026-02-12T17:01:38.873777Z","steps":["trace[77515102] 'read index received' (duration: 89.557242ms)","trace[77515102] 'applied index is now lower than readState.Index' (duration: 297.910517ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:38.87392Z","caller":"traceutil/trace.go:171","msg":"trace[1289630247] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"459.689489ms","start":"2026-02-12T17:01:38.414225Z","end":"2026-02-12T17:01:38.873914Z","steps":["trace[1289630247] 'process raft request' (duration: 161.668999ms)","trace[1289630247] 'compare' (duration: 297.680512ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:38.873952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:38.414198Z","time spent":"459.73658ms","remote":"127.0.0.1:58910","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-12T17:01:38.874095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.793736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T17:01:38.874108Z","caller":"traceutil/trace.go:171","msg":"trace[2115925606] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:765; }","duration":"387.862537ms","start":"2026-02-12T17:01:38.486241Z","end":"2026-02-12T17:01:38.874104Z","steps":["trace[2115925606] 'agreement among raft nodes before linearized reading' (duration: 387.813276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:38.874123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:38.486205Z","time spent":"387.914329ms","remote":"127.0.0.1:58844","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":40,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-02-12T17:01:38.874246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.646096ms","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-12T17:01:38.874261Z","caller":"traceutil/trace.go:171","msg":"trace[632547387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:765; }","duration":"130.698397ms","start":"2026-02-12T17:01:38.743558Z","end":"2026-02-12T17:01:38.874257Z","steps":["trace[632547387] 'agreement among raft nodes before linearized reading' (duration: 130.636555ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:51.08279Z","caller":"traceutil/trace.go:171","msg":"trace[2098548802] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"120.084093ms","start":"2026-02-12T17:01:50.962686Z","end":"2026-02-12T17:01:51.08277Z","steps":["trace[2098548802] 'process raft request' (duration: 119.721255ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:53.991513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:53.507727Z","time spent":"483.78289ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-02-12T17:01:54.71919Z","caller":"traceutil/trace.go:171","msg":"trace[2133109646] transaction","detail":"{read_only:false; response_revision:809; number_of_response:1; }","duration":"108.343405ms","start":"2026-02-12T17:01:54.610824Z","end":"2026-02-12T17:01:54.719168Z","steps":["trace[2133109646] 'process raft request' (duration: 108.235083ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:55.303889Z","caller":"traceutil/trace.go:171","msg":"trace[269136247] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"132.802884ms","start":"2026-02-12T17:01:55.171061Z","end":"2026-02-12T17:01:55.303864Z","steps":["trace[269136247] 'process raft request' (duration: 33.313514ms)","trace[269136247] 'compare' (duration: 99.275654ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:56.520827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.356289ms","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-12T17:01:56.524784Z","caller":"traceutil/trace.go:171","msg":"trace[1327788478] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:811; }","duration":"104.317936ms","start":"2026-02-12T17:01:56.420401Z","end":"2026-02-12T17:01:56.524719Z","steps":["trace[1327788478] 'range keys from in-memory index tree' (duration: 100.185515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:56.521865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"566.937638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:56.525901Z","caller":"traceutil/trace.go:171","msg":"trace[1475619402] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:811; }","duration":"571.002088ms","start":"2026-02-12T17:01:55.954885Z","end":"2026-02-12T17:01:56.525887Z","steps":["trace[1475619402] 'range keys from in-memory index tree' (duration: 566.849406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:56.529914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:55.954862Z","time spent":"575.030896ms","remote":"127.0.0.1:58624","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-12T17:01:56.921725Z","caller":"traceutil/trace.go:171","msg":"trace[857747355] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"388.427479ms","start":"2026-02-12T17:01:56.533275Z","end":"2026-02-12T17:01:56.921702Z","steps":["trace[857747355] 'process raft request' (duration: 388.03391ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:56.921872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:56.533263Z","time spent":"388.530192ms","remote":"127.0.0.1:58910","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-12T17:01:56.925821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.833559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:01:56.925866Z","caller":"traceutil/trace.go:171","msg":"trace[1582910923] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"333.91362ms","start":"2026-02-12T17:01:56.591941Z","end":"2026-02-12T17:01:56.925854Z","steps":["trace[1582910923] 'agreement among raft nodes before linearized reading' (duration: 333.758436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:56.925899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:56.59191Z","time spent":"333.982262ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T17:01:56.927273Z","caller":"traceutil/trace.go:171","msg":"trace[984202011] linearizableReadLoop","detail":"{readStateIndex:840; appliedIndex:839; }","duration":"329.478912ms","start":"2026-02-12T17:01:56.591984Z","end":"2026-02-12T17:01:56.921463Z","steps":["trace[984202011] 'read index received' (duration: 324.549063ms)","trace[984202011] 'applied index is now lower than readState.Index' (duration: 4.928719ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:56.927489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.473728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:01:56.927526Z","caller":"traceutil/trace.go:171","msg":"trace[1119872963] range","detail":"{range_begin:/registry/ingressclasses/; range_end:/registry/ingressclasses0; response_count:0; response_revision:812; }","duration":"302.538829ms","start":"2026-02-12T17:01:56.624975Z","end":"2026-02-12T17:01:56.927514Z","steps":["trace[1119872963] 'agreement among raft nodes before linearized reading' (duration: 302.473928ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:56.92756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:56.624963Z","time spent":"302.58543ms","remote":"127.0.0.1:58934","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/ingressclasses/\" range_end:\"/registry/ingressclasses0\" count_only:true "} {"level":"warn","ts":"2026-02-12T17:01:56.928292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.370154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:01:56.92831Z","caller":"traceutil/trace.go:171","msg":"trace[1118680762] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"262.424515ms","start":"2026-02-12T17:01:56.665881Z","end":"2026-02-12T17:01:56.928306Z","steps":["trace[1118680762] 'agreement among raft nodes before linearized reading' (duration: 261.840513ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:57.162666Z","caller":"traceutil/trace.go:171","msg":"trace[1409230507] transaction","detail":"{read_only:false; response_revision:813; number_of_response:1; }","duration":"226.7008ms","start":"2026-02-12T17:01:56.935705Z","end":"2026-02-12T17:01:57.162405Z","steps":["trace[1409230507] 'process raft request' (duration: 203.280085ms)","trace[1409230507] 'compare' (duration: 23.307673ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:57.825275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"481.180122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:01:57.825367Z","caller":"traceutil/trace.go:171","msg":"trace[212680893] linearizableReadLoop","detail":"{readStateIndex:842; appliedIndex:841; }","duration":"654.188059ms","start":"2026-02-12T17:01:57.171162Z","end":"2026-02-12T17:01:57.82535Z","steps":["trace[212680893] 'read index received' (duration: 172.786453ms)","trace[212680893] 'applied index is now lower than readState.Index' (duration: 481.400216ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:57.825597Z","caller":"traceutil/trace.go:171","msg":"trace[2045486285] transaction","detail":"{read_only:false; response_revision:814; number_of_response:1; }","duration":"882.665108ms","start":"2026-02-12T17:01:56.942908Z","end":"2026-02-12T17:01:57.825574Z","steps":["trace[2045486285] 'process raft request' (duration: 401.089928ms)","trace[2045486285] 'compare' (duration: 480.859594ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:01:57.825662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:56.942898Z","time spent":"882.730179ms","remote":"127.0.0.1:58910","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-12T17:01:57.825899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.735121ms","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-12T17:01:57.825929Z","caller":"traceutil/trace.go:171","msg":"trace[623993938] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:814; }","duration":"654.785653ms","start":"2026-02-12T17:01:57.171133Z","end":"2026-02-12T17:01:57.825919Z","steps":["trace[623993938] 'agreement among raft nodes before linearized reading' (duration: 654.6762ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:57.825959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:57.171116Z","time spent":"654.835963ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-12T17:01:58.005475Z","caller":"traceutil/trace.go:171","msg":"trace[592788536] linearizableReadLoop","detail":"{readStateIndex:843; appliedIndex:842; }","duration":"180.062404ms","start":"2026-02-12T17:01:57.825392Z","end":"2026-02-12T17:01:58.005454Z","steps":["trace[592788536] 'read index received' (duration: 172.865515ms)","trace[592788536] 'applied index is now lower than readState.Index' (duration: 7.196149ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:01:58.005649Z","caller":"traceutil/trace.go:171","msg":"trace[1141260891] transaction","detail":"{read_only:false; response_revision:815; number_of_response:1; }","duration":"448.929972ms","start":"2026-02-12T17:01:57.55671Z","end":"2026-02-12T17:01:58.00564Z","steps":["trace[1141260891] 'process raft request' (duration: 441.58483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:58.005728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:57.556687Z","time spent":"448.980392ms","remote":"127.0.0.1:58910","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-12T17:01:58.005905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"696.705145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-12T17:01:58.005925Z","caller":"traceutil/trace.go:171","msg":"trace[1424414563] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:815; }","duration":"696.757577ms","start":"2026-02-12T17:01:57.309161Z","end":"2026-02-12T17:01:58.005919Z","steps":["trace[1424414563] 'agreement among raft nodes before linearized reading' (duration: 696.672915ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:01:58.005943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:01:57.309143Z","time spent":"696.797188ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T17:01:58.006746Z","caller":"traceutil/trace.go:171","msg":"trace[725793909] transaction","detail":"{read_only:false; response_revision:816; number_of_response:1; }","duration":"175.508613ms","start":"2026-02-12T17:01:57.831228Z","end":"2026-02-12T17:01:58.006737Z","steps":["trace[725793909] 'process raft request' (duration: 175.204806ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:01:58.208562Z","caller":"traceutil/trace.go:171","msg":"trace[1087998934] transaction","detail":"{read_only:false; response_revision:817; number_of_response:1; }","duration":"195.736458ms","start":"2026-02-12T17:01:58.012802Z","end":"2026-02-12T17:01:58.208538Z","steps":["trace[1087998934] 'process raft request' (duration: 194.170974ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:07.374767Z","caller":"traceutil/trace.go:171","msg":"trace[157082999] transaction","detail":"{read_only:false; response_revision:856; number_of_response:1; }","duration":"107.316332ms","start":"2026-02-12T17:02:07.26741Z","end":"2026-02-12T17:02:07.374726Z","steps":["trace[157082999] 'process raft request' (duration: 105.769049ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:09.993244Z","caller":"traceutil/trace.go:171","msg":"trace[1617752387] transaction","detail":"{read_only:false; response_revision:900; number_of_response:1; }","duration":"113.536058ms","start":"2026-02-12T17:02:09.879692Z","end":"2026-02-12T17:02:09.993228Z","steps":["trace[1617752387] 'process raft request' (duration: 113.16135ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:12.418134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.535099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-02-12T17:02:12.41821Z","caller":"traceutil/trace.go:171","msg":"trace[1870007422] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:906; }","duration":"108.684633ms","start":"2026-02-12T17:02:12.309508Z","end":"2026-02-12T17:02:12.418192Z","steps":["trace[1870007422] 'range keys from in-memory index tree' (duration: 108.374516ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:13.039768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.881857ms","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-12T17:02:13.039835Z","caller":"traceutil/trace.go:171","msg":"trace[1108967229] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:907; }","duration":"302.996159ms","start":"2026-02-12T17:02:12.736823Z","end":"2026-02-12T17:02:13.039819Z","steps":["trace[1108967229] 'count revisions from in-memory index tree' (duration: 302.766404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:13.039862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:12.736792Z","time spent":"303.06399ms","remote":"127.0.0.1:58992","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":68,"response size":30,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true "} {"level":"warn","ts":"2026-02-12T17:02:14.531853Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:15.032048Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498427,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:15.314078Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.772013052s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-12T17:02:15.314892Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:13.541991Z","time spent":"1.772893702s","remote":"127.0.0.1:58656","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T17:02:16.543743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"677.037331ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:16.543962Z","caller":"traceutil/trace.go:171","msg":"trace[1842904402] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"1.224226245s","start":"2026-02-12T17:02:15.319718Z","end":"2026-02-12T17:02:16.543945Z","steps":["trace[1842904402] 'process raft request' (duration: 1.224124383s)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:16.543958Z","caller":"traceutil/trace.go:171","msg":"trace[1829850339] transaction","detail":"{read_only:false; response_revision:910; number_of_response:1; }","duration":"1.660307014s","start":"2026-02-12T17:02:14.88362Z","end":"2026-02-12T17:02:16.543927Z","steps":["trace[1829850339] 'process raft request' (duration: 982.447984ms)","trace[1829850339] 'compare' (duration: 676.181803ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:16.544039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:15.319693Z","time spent":"1.224310868s","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:16.544136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:14.883502Z","time spent":"1.660542578s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:16.544237Z","caller":"traceutil/trace.go:171","msg":"trace[1128316729] linearizableReadLoop","detail":"{readStateIndex:943; appliedIndex:941; }","duration":"2.51247072s","start":"2026-02-12T17:02:14.031756Z","end":"2026-02-12T17:02:16.544226Z","steps":["trace[1128316729] 'read index received' (duration: 1.282754054s)","trace[1128316729] 'applied index is now lower than readState.Index' (duration: 1.229715346s)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:16.544439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.512684715s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:02:16.544468Z","caller":"traceutil/trace.go:171","msg":"trace[1410969832] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:911; }","duration":"2.512738395s","start":"2026-02-12T17:02:14.03172Z","end":"2026-02-12T17:02:16.544458Z","steps":["trace[1410969832] 'agreement among raft nodes before linearized reading' (duration: 2.512597792s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.544496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:14.031664Z","time spent":"2.512826018s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:16.544559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.432309275s","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-12T17:02:16.544597Z","caller":"traceutil/trace.go:171","msg":"trace[1454539676] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:911; }","duration":"2.432380507s","start":"2026-02-12T17:02:14.112205Z","end":"2026-02-12T17:02:16.544586Z","steps":["trace[1454539676] 'agreement among raft nodes before linearized reading' (duration: 2.432230524s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.544633Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:14.112184Z","time spent":"2.432442959s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:16.544795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.903858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-12T17:02:16.544822Z","caller":"traceutil/trace.go:171","msg":"trace[176150309] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:911; }","duration":"109.95507ms","start":"2026-02-12T17:02:16.434858Z","end":"2026-02-12T17:02:16.544813Z","steps":["trace[176150309] 'agreement among raft nodes before linearized reading' (duration: 109.910819ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299085893s","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-12T17:02:16.545038Z","caller":"traceutil/trace.go:171","msg":"trace[1700310919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:911; }","duration":"1.299681157s","start":"2026-02-12T17:02:15.245348Z","end":"2026-02-12T17:02:16.54503Z","steps":["trace[1700310919] 'agreement among raft nodes before linearized reading' (duration: 1.299603505s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:15.24529Z","time spent":"1.299766218s","remote":"127.0.0.1:58910","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-12T17:02:16.545175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.377598182s","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-12T17:02:16.545198Z","caller":"traceutil/trace.go:171","msg":"trace[975521089] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:911; }","duration":"1.377655002s","start":"2026-02-12T17:02:15.167536Z","end":"2026-02-12T17:02:16.545191Z","steps":["trace[975521089] 'agreement among raft nodes before linearized reading' (duration: 1.377581141s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:15.167515Z","time spent":"1.377700884s","remote":"127.0.0.1:58910","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-12T17:02:16.545294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.460852262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:16.545317Z","caller":"traceutil/trace.go:171","msg":"trace[787835731] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:911; }","duration":"1.460934645s","start":"2026-02-12T17:02:15.084376Z","end":"2026-02-12T17:02:16.545311Z","steps":["trace[787835731] 'agreement among raft nodes before linearized reading' (duration: 1.460898234s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:15.084347Z","time spent":"1.460988665s","remote":"127.0.0.1:58924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-02-12T17:02:16.545481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.578786158s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:16.545506Z","caller":"traceutil/trace.go:171","msg":"trace[784554523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:911; }","duration":"1.57885303s","start":"2026-02-12T17:02:14.966645Z","end":"2026-02-12T17:02:16.545498Z","steps":["trace[784554523] 'agreement among raft nodes before linearized reading' (duration: 1.578816239s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:14.966618Z","time spent":"1.578906101s","remote":"127.0.0.1:58624","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-12T17:02:16.545696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.104731425s","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-12T17:02:16.545721Z","caller":"traceutil/trace.go:171","msg":"trace[1747853697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:911; }","duration":"2.104784926s","start":"2026-02-12T17:02:14.440929Z","end":"2026-02-12T17:02:16.545713Z","steps":["trace[1747853697] 'agreement among raft nodes before linearized reading' (duration: 2.104712864s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:16.545745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:14.44091Z","time spent":"2.104829327s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-12T17:02:22.709457Z","caller":"traceutil/trace.go:171","msg":"trace[251529291] transaction","detail":"{read_only:false; response_revision:928; number_of_response:1; }","duration":"103.355713ms","start":"2026-02-12T17:02:22.606082Z","end":"2026-02-12T17:02:22.709438Z","steps":["trace[251529291] 'process raft request' (duration: 103.21781ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:23.002307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.622386ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:23.002467Z","caller":"traceutil/trace.go:171","msg":"trace[865821431] linearizableReadLoop","detail":"{readStateIndex:965; appliedIndex:962; }","duration":"284.863381ms","start":"2026-02-12T17:02:22.717592Z","end":"2026-02-12T17:02:23.002456Z","steps":["trace[865821431] 'read index received' (duration: 93.977479ms)","trace[865821431] 'applied index is now lower than readState.Index' (duration: 190.885262ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:23.002552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.951542ms","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-12T17:02:23.002569Z","caller":"traceutil/trace.go:171","msg":"trace[1911114259] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:932; }","duration":"284.992503ms","start":"2026-02-12T17:02:22.717569Z","end":"2026-02-12T17:02:23.002561Z","steps":["trace[1911114259] 'agreement among raft nodes before linearized reading' (duration: 284.915441ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:23.002798Z","caller":"traceutil/trace.go:171","msg":"trace[196445961] transaction","detail":"{read_only:false; response_revision:929; number_of_response:1; }","duration":"288.604023ms","start":"2026-02-12T17:02:22.714186Z","end":"2026-02-12T17:02:23.00279Z","steps":["trace[196445961] 'process raft request' (duration: 97.426845ms)","trace[196445961] 'compare' (duration: 190.37157ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:23.002882Z","caller":"traceutil/trace.go:171","msg":"trace[1058906472] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"288.525261ms","start":"2026-02-12T17:02:22.714351Z","end":"2026-02-12T17:02:23.002877Z","steps":["trace[1058906472] 'process raft request' (duration: 288.02575ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:23.002948Z","caller":"traceutil/trace.go:171","msg":"trace[1196450424] transaction","detail":"{read_only:false; response_revision:931; number_of_response:1; }","duration":"286.902244ms","start":"2026-02-12T17:02:22.716042Z","end":"2026-02-12T17:02:23.002945Z","steps":["trace[1196450424] 'process raft request' (duration: 286.359842ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:23.003014Z","caller":"traceutil/trace.go:171","msg":"trace[1739447680] transaction","detail":"{read_only:false; response_revision:932; number_of_response:1; }","duration":"284.92649ms","start":"2026-02-12T17:02:22.718083Z","end":"2026-02-12T17:02:23.003009Z","steps":["trace[1739447680] 'process raft request' (duration: 284.335888ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:24.542714Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498489,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:24.865838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.11173627s","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-12T17:02:24.86593Z","caller":"traceutil/trace.go:171","msg":"trace[1887072300] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:932; }","duration":"1.111838502s","start":"2026-02-12T17:02:23.754075Z","end":"2026-02-12T17:02:24.865914Z","steps":["trace[1887072300] 'range keys from in-memory index tree' (duration: 1.11172338s)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:24.867761Z","caller":"traceutil/trace.go:171","msg":"trace[1589578831] linearizableReadLoop","detail":"{readStateIndex:968; appliedIndex:967; }","duration":"916.416161ms","start":"2026-02-12T17:02:23.951335Z","end":"2026-02-12T17:02:24.867752Z","steps":["trace[1589578831] 'read index received' (duration: 916.276988ms)","trace[1589578831] 'applied index is now lower than readState.Index' (duration: 138.703µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:24.867816Z","caller":"traceutil/trace.go:171","msg":"trace[1678146942] transaction","detail":"{read_only:false; response_revision:933; number_of_response:1; }","duration":"1.176965385s","start":"2026-02-12T17:02:23.690842Z","end":"2026-02-12T17:02:24.867808Z","steps":["trace[1678146942] 'process raft request' (duration: 1.176817272s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:24.867893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:23.690815Z","time spent":"1.177028717s","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:24.868048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"916.718497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:24.868067Z","caller":"traceutil/trace.go:171","msg":"trace[476716419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:933; }","duration":"916.780119ms","start":"2026-02-12T17:02:23.951282Z","end":"2026-02-12T17:02:24.868062Z","steps":["trace[476716419] 'agreement among raft nodes before linearized reading' (duration: 916.729158ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:24.868082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:23.951264Z","time spent":"916.815219ms","remote":"127.0.0.1:58636","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-12T17:02:24.868235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.957198ms","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-12T17:02:24.868254Z","caller":"traceutil/trace.go:171","msg":"trace[726381899] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:933; }","duration":"154.02141ms","start":"2026-02-12T17:02:24.714228Z","end":"2026-02-12T17:02:24.868249Z","steps":["trace[726381899] 'agreement among raft nodes before linearized reading' (duration: 153.953188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.373215Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498496,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:25.532146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.020789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:25.532234Z","caller":"traceutil/trace.go:171","msg":"trace[785548739] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:968; }","duration":"659.741ms","start":"2026-02-12T17:02:24.87248Z","end":"2026-02-12T17:02:25.532221Z","steps":["trace[785548739] 'read index received' (duration: 555.514926ms)","trace[785548739] 'applied index is now lower than readState.Index' (duration: 104.224874ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:25.532429Z","caller":"traceutil/trace.go:171","msg":"trace[205906812] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"660.875347ms","start":"2026-02-12T17:02:24.87154Z","end":"2026-02-12T17:02:25.532415Z","steps":["trace[205906812] 'process raft request' (duration: 556.51ms)","trace[205906812] 'compare' (duration: 103.918237ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:25.53248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:24.871533Z","time spent":"660.920727ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:25.533039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"660.548498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T17:02:25.533073Z","caller":"traceutil/trace.go:171","msg":"trace[691280811] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:934; }","duration":"660.59659ms","start":"2026-02-12T17:02:24.872469Z","end":"2026-02-12T17:02:25.533066Z","steps":["trace[691280811] 'agreement among raft nodes before linearized reading' (duration: 660.13368ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.533092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:24.872465Z","time spent":"660.62311ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"warn","ts":"2026-02-12T17:02:25.533281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"521.022838ms","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-12T17:02:25.533302Z","caller":"traceutil/trace.go:171","msg":"trace[1091506018] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:934; }","duration":"521.064549ms","start":"2026-02-12T17:02:25.012228Z","end":"2026-02-12T17:02:25.533293Z","steps":["trace[1091506018] 'agreement among raft nodes before linearized reading' (duration: 520.979047ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.533317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:25.012211Z","time spent":"521.10219ms","remote":"127.0.0.1:58910","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-12T17:02:25.53355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"511.186602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:25.533567Z","caller":"traceutil/trace.go:171","msg":"trace[1104356282] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:934; }","duration":"511.203512ms","start":"2026-02-12T17:02:25.022358Z","end":"2026-02-12T17:02:25.533562Z","steps":["trace[1104356282] 'agreement among raft nodes before linearized reading' (duration: 511.174652ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.533684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:25.022341Z","time spent":"511.334205ms","remote":"127.0.0.1:58624","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-12T17:02:25.533836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.924696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:02:25.533853Z","caller":"traceutil/trace.go:171","msg":"trace[756834958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:934; }","duration":"515.948548ms","start":"2026-02-12T17:02:25.017899Z","end":"2026-02-12T17:02:25.533848Z","steps":["trace[756834958] 'agreement among raft nodes before linearized reading' (duration: 515.871876ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.533867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:25.017896Z","time spent":"515.967218ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:25.534562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"516.667713ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:02:25.534583Z","caller":"traceutil/trace.go:171","msg":"trace[1743959111] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:934; }","duration":"516.705273ms","start":"2026-02-12T17:02:25.017872Z","end":"2026-02-12T17:02:25.534578Z","steps":["trace[1743959111] 'agreement among raft nodes before linearized reading' (duration: 516.060719ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.5346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:25.01786Z","time spent":"516.735764ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:25.534716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.438599ms","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-12T17:02:25.534733Z","caller":"traceutil/trace.go:171","msg":"trace[422978695] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:934; }","duration":"522.46347ms","start":"2026-02-12T17:02:25.012265Z","end":"2026-02-12T17:02:25.534728Z","steps":["trace[422978695] 'agreement among raft nodes before linearized reading' (duration: 522.413918ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:25.534772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:25.012262Z","time spent":"522.48119ms","remote":"127.0.0.1:58910","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-12T17:02:25.683662Z","caller":"traceutil/trace.go:171","msg":"trace[2044244289] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"115.387809ms","start":"2026-02-12T17:02:25.568258Z","end":"2026-02-12T17:02:25.683645Z","steps":["trace[2044244289] 'process raft request' (duration: 115.354338ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:25.683693Z","caller":"traceutil/trace.go:171","msg":"trace[2077030377] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"138.343745ms","start":"2026-02-12T17:02:25.545322Z","end":"2026-02-12T17:02:25.683666Z","steps":["trace[2077030377] 'process raft request' (duration: 119.608352ms)","trace[2077030377] 'compare' (duration: 18.549689ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:25.683908Z","caller":"traceutil/trace.go:171","msg":"trace[1539312377] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"131.080076ms","start":"2026-02-12T17:02:25.552823Z","end":"2026-02-12T17:02:25.683903Z","steps":["trace[1539312377] 'process raft request' (duration: 130.755739ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:27.44889Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498505,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:02:27.551278Z","caller":"traceutil/trace.go:171","msg":"trace[142608124] linearizableReadLoop","detail":"{readStateIndex:974; appliedIndex:973; }","duration":"602.542272ms","start":"2026-02-12T17:02:26.948721Z","end":"2026-02-12T17:02:27.551263Z","steps":["trace[142608124] 'read index received' (duration: 602.419469ms)","trace[142608124] 'applied index is now lower than readState.Index' (duration: 121.983µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:27.551473Z","caller":"traceutil/trace.go:171","msg":"trace[1612084358] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"627.577613ms","start":"2026-02-12T17:02:26.92389Z","end":"2026-02-12T17:02:27.551467Z","steps":["trace[1612084358] 'process raft request' (duration: 627.289207ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:27.551548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:26.923872Z","time spent":"627.622965ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:27.551657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.942511ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:27.551678Z","caller":"traceutil/trace.go:171","msg":"trace[609044903] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"602.989612ms","start":"2026-02-12T17:02:26.948684Z","end":"2026-02-12T17:02:27.551673Z","steps":["trace[609044903] 'agreement among raft nodes before linearized reading' (duration: 602.957601ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:27.551696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:26.948646Z","time spent":"603.047393ms","remote":"127.0.0.1:58624","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-12T17:02:28.108123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:28.608448Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:28.946783Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.382376756s","expected-duration":"1s"} {"level":"info","ts":"2026-02-12T17:02:28.993363Z","caller":"traceutil/trace.go:171","msg":"trace[1272240999] linearizableReadLoop","detail":"{readStateIndex:975; appliedIndex:974; }","duration":"1.385511636s","start":"2026-02-12T17:02:27.607822Z","end":"2026-02-12T17:02:28.993333Z","steps":["trace[1272240999] 'read index received' (duration: 1.356261912s)","trace[1272240999] 'applied index is now lower than readState.Index' (duration: 29.248404ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:28.993624Z","caller":"traceutil/trace.go:171","msg":"trace[1712853424] transaction","detail":"{read_only:false; response_revision:940; number_of_response:1; }","duration":"1.42926836s","start":"2026-02-12T17:02:27.564342Z","end":"2026-02-12T17:02:28.99361Z","steps":["trace[1712853424] 'process raft request' (duration: 1.399801881s)","trace[1712853424] 'compare' (duration: 29.05304ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:28.993735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:27.564325Z","time spent":"1.429332301s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:28.993982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.38615263s","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-12T17:02:28.994014Z","caller":"traceutil/trace.go:171","msg":"trace[718394862] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:940; }","duration":"1.386210932s","start":"2026-02-12T17:02:27.607793Z","end":"2026-02-12T17:02:28.994004Z","steps":["trace[718394862] 'agreement among raft nodes before linearized reading' (duration: 1.386093509s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:28.994046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:27.607778Z","time spent":"1.386262262s","remote":"127.0.0.1:58910","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-12T17:02:28.996165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.308594674s","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-12T17:02:28.996207Z","caller":"traceutil/trace.go:171","msg":"trace[2134774648] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:941; }","duration":"1.308640235s","start":"2026-02-12T17:02:27.687555Z","end":"2026-02-12T17:02:28.996195Z","steps":["trace[2134774648] 'agreement among raft nodes before linearized reading' (duration: 1.308515062s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:28.996242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:27.68751Z","time spent":"1.308721017s","remote":"127.0.0.1:58910","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-12T17:02:28.996342Z","caller":"traceutil/trace.go:171","msg":"trace[1256899182] transaction","detail":"{read_only:false; response_revision:941; number_of_response:1; }","duration":"383.271677ms","start":"2026-02-12T17:02:28.613052Z","end":"2026-02-12T17:02:28.996324Z","steps":["trace[1256899182] 'process raft request' (duration: 382.94893ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:28.996455Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.306941228s","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-12T17:02:28.996487Z","caller":"traceutil/trace.go:171","msg":"trace[1324397848] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:941; }","duration":"1.306983668s","start":"2026-02-12T17:02:27.689495Z","end":"2026-02-12T17:02:28.996479Z","steps":["trace[1324397848] 'agreement among raft nodes before linearized reading' (duration: 1.306893476s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:28.996515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:27.689489Z","time spent":"1.307018899s","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:28.996458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:28.613034Z","time spent":"383.355498ms","remote":"127.0.0.1:58910","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-12T17:02:28.997387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.309506903s","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-12T17:02:28.997426Z","caller":"traceutil/trace.go:171","msg":"trace[1955649671] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:941; }","duration":"1.309554874s","start":"2026-02-12T17:02:27.687863Z","end":"2026-02-12T17:02:28.997418Z","steps":["trace[1955649671] 'agreement among raft nodes before linearized reading' (duration: 1.308756146s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:28.997446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:27.687857Z","time spent":"1.309586074s","remote":"127.0.0.1:58910","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-12T17:02:29.243068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.498448ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:29.243147Z","caller":"traceutil/trace.go:171","msg":"trace[734734278] transaction","detail":"{read_only:false; response_revision:942; number_of_response:1; }","duration":"240.294639ms","start":"2026-02-12T17:02:29.002838Z","end":"2026-02-12T17:02:29.243133Z","steps":["trace[734734278] 'process raft request' (duration: 111.669298ms)","trace[734734278] 'compare' (duration: 128.402366ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:29.320286Z","caller":"traceutil/trace.go:171","msg":"trace[792080809] transaction","detail":"{read_only:false; response_revision:944; number_of_response:1; }","duration":"315.746369ms","start":"2026-02-12T17:02:29.004494Z","end":"2026-02-12T17:02:29.32024Z","steps":["trace[792080809] 'process raft request' (duration: 315.664967ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:29.320447Z","caller":"traceutil/trace.go:171","msg":"trace[1746494995] transaction","detail":"{read_only:false; response_revision:945; number_of_response:1; }","duration":"314.546153ms","start":"2026-02-12T17:02:29.005879Z","end":"2026-02-12T17:02:29.320425Z","steps":["trace[1746494995] 'process raft request' (duration: 314.323808ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:29.320458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:29.00449Z","time spent":"315.886352ms","remote":"127.0.0.1:58910","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-12T17:02:29.320558Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:29.005871Z","time spent":"314.620914ms","remote":"127.0.0.1:58910","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-12T17:02:29.320702Z","caller":"traceutil/trace.go:171","msg":"trace[1296424390] transaction","detail":"{read_only:false; response_revision:943; number_of_response:1; }","duration":"316.2448ms","start":"2026-02-12T17:02:29.004452Z","end":"2026-02-12T17:02:29.320696Z","steps":["trace[1296424390] 'process raft request' (duration: 315.583665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:29.320728Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:29.004443Z","time spent":"316.27125ms","remote":"127.0.0.1:58910","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-12T17:02:31.563876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.652199ms","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-12T17:02:31.563931Z","caller":"traceutil/trace.go:171","msg":"trace[469275402] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:946; }","duration":"221.761931ms","start":"2026-02-12T17:02:31.342157Z","end":"2026-02-12T17:02:31.563919Z","steps":["trace[469275402] 'range keys from in-memory index tree' (duration: 221.468265ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:31.564001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.69585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:02:31.564052Z","caller":"traceutil/trace.go:171","msg":"trace[309303009] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:946; }","duration":"221.756281ms","start":"2026-02-12T17:02:31.342282Z","end":"2026-02-12T17:02:31.564039Z","steps":["trace[309303009] 'range keys from in-memory index tree' (duration: 221.607337ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:31.564291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.044138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:02:31.564319Z","caller":"traceutil/trace.go:171","msg":"trace[1577572706] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:946; }","duration":"222.071328ms","start":"2026-02-12T17:02:31.342234Z","end":"2026-02-12T17:02:31.564306Z","steps":["trace[1577572706] 'range keys from in-memory index tree' (duration: 221.998046ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:31.564394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.471982ms","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-12T17:02:31.564407Z","caller":"traceutil/trace.go:171","msg":"trace[1175479656] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:946; }","duration":"314.509502ms","start":"2026-02-12T17:02:31.249893Z","end":"2026-02-12T17:02:31.564403Z","steps":["trace[1175479656] 'range keys from in-memory index tree' (duration: 314.40912ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:31.56442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:31.249878Z","time spent":"314.537393ms","remote":"127.0.0.1:58910","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":"info","ts":"2026-02-12T17:02:31.683032Z","caller":"traceutil/trace.go:171","msg":"trace[857744416] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"108.452907ms","start":"2026-02-12T17:02:31.574559Z","end":"2026-02-12T17:02:31.683012Z","steps":["trace[857744416] 'process raft request' (duration: 108.331685ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:31.744131Z","caller":"traceutil/trace.go:171","msg":"trace[1583349267] transaction","detail":"{read_only:false; response_revision:950; number_of_response:1; }","duration":"168.918569ms","start":"2026-02-12T17:02:31.575195Z","end":"2026-02-12T17:02:31.744113Z","steps":["trace[1583349267] 'process raft request' (duration: 168.893988ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:31.74436Z","caller":"traceutil/trace.go:171","msg":"trace[279221381] transaction","detail":"{read_only:false; response_revision:949; number_of_response:1; }","duration":"169.171744ms","start":"2026-02-12T17:02:31.575163Z","end":"2026-02-12T17:02:31.744334Z","steps":["trace[279221381] 'process raft request' (duration: 168.906859ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:31.744501Z","caller":"traceutil/trace.go:171","msg":"trace[1860533659] transaction","detail":"{read_only:false; response_revision:948; number_of_response:1; }","duration":"169.40111ms","start":"2026-02-12T17:02:31.575092Z","end":"2026-02-12T17:02:31.744493Z","steps":["trace[1860533659] 'process raft request' (duration: 168.879247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:32.381523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.818836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:32.381573Z","caller":"traceutil/trace.go:171","msg":"trace[1290386066] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:950; }","duration":"432.930598ms","start":"2026-02-12T17:02:31.948631Z","end":"2026-02-12T17:02:32.381562Z","steps":["trace[1290386066] 'range keys from in-memory index tree' (duration: 432.729104ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:32.381596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:31.948612Z","time spent":"432.980019ms","remote":"127.0.0.1:58624","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-12T17:02:33.625975Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498537,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-12T17:02:33.880603Z","caller":"traceutil/trace.go:171","msg":"trace[2029980386] linearizableReadLoop","detail":"{readStateIndex:987; appliedIndex:986; }","duration":"755.50787ms","start":"2026-02-12T17:02:33.125071Z","end":"2026-02-12T17:02:33.880579Z","steps":["trace[2029980386] 'read index received' (duration: 755.315436ms)","trace[2029980386] 'applied index is now lower than readState.Index' (duration: 191.354µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:33.881123Z","caller":"traceutil/trace.go:171","msg":"trace[434399252] transaction","detail":"{read_only:false; response_revision:951; number_of_response:1; }","duration":"810.568171ms","start":"2026-02-12T17:02:33.070517Z","end":"2026-02-12T17:02:33.881085Z","steps":["trace[434399252] 'process raft request' (duration: 809.931857ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.881252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:33.070499Z","time spent":"810.669112ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:33.881599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.515962ms","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-12T17:02:33.881646Z","caller":"traceutil/trace.go:171","msg":"trace[987679104] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:951; }","duration":"756.595224ms","start":"2026-02-12T17:02:33.125033Z","end":"2026-02-12T17:02:33.881628Z","steps":["trace[987679104] 'agreement among raft nodes before linearized reading' (duration: 756.501612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.881695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:33.125012Z","time spent":"756.672546ms","remote":"127.0.0.1:60026","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-12T17:02:33.881996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.730037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T17:02:33.882041Z","caller":"traceutil/trace.go:171","msg":"trace[675166147] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:951; }","duration":"373.814159ms","start":"2026-02-12T17:02:33.508216Z","end":"2026-02-12T17:02:33.88203Z","steps":["trace[675166147] 'agreement among raft nodes before linearized reading' (duration: 373.678036ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.882078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:33.508194Z","time spent":"373.87658ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.17\" "} {"level":"warn","ts":"2026-02-12T17:02:33.8824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.84228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:02:33.882444Z","caller":"traceutil/trace.go:171","msg":"trace[1936231283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:951; }","duration":"125.895232ms","start":"2026-02-12T17:02:33.756537Z","end":"2026-02-12T17:02:33.882433Z","steps":["trace[1936231283] 'agreement among raft nodes before linearized reading' (duration: 125.782629ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.882957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.484974ms","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-12T17:02:33.883007Z","caller":"traceutil/trace.go:171","msg":"trace[1980443064] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:951; }","duration":"126.541456ms","start":"2026-02-12T17:02:33.756446Z","end":"2026-02-12T17:02:33.882988Z","steps":["trace[1980443064] 'agreement among raft nodes before linearized reading' (duration: 126.443244ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.882843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.398919ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:02:33.883188Z","caller":"traceutil/trace.go:171","msg":"trace[874298566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:951; }","duration":"194.802438ms","start":"2026-02-12T17:02:33.688369Z","end":"2026-02-12T17:02:33.883171Z","steps":["trace[874298566] 'agreement among raft nodes before linearized reading' (duration: 194.274586ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:33.883308Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.978149ms","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-12T17:02:33.883342Z","caller":"traceutil/trace.go:171","msg":"trace[243006495] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:951; }","duration":"134.03515ms","start":"2026-02-12T17:02:33.749294Z","end":"2026-02-12T17:02:33.883329Z","steps":["trace[243006495] 'agreement among raft nodes before linearized reading' (duration: 133.950958ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.343824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.887582ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:38.343946Z","caller":"traceutil/trace.go:171","msg":"trace[899066782] linearizableReadLoop","detail":"{readStateIndex:1018; appliedIndex:1016; }","duration":"392.426897ms","start":"2026-02-12T17:02:37.951507Z","end":"2026-02-12T17:02:38.343934Z","steps":["trace[899066782] 'read index received' (duration: 68.788454ms)","trace[899066782] 'applied index is now lower than readState.Index' (duration: 323.637213ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:38.344146Z","caller":"traceutil/trace.go:171","msg":"trace[318857344] transaction","detail":"{read_only:false; response_revision:980; number_of_response:1; }","duration":"400.659419ms","start":"2026-02-12T17:02:37.943475Z","end":"2026-02-12T17:02:38.344135Z","steps":["trace[318857344] 'process raft request' (duration: 233.397957ms)","trace[318857344] 'compare' (duration: 166.806891ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:38.344194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:37.943466Z","time spent":"400.705909ms","remote":"127.0.0.1:58910","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-12T17:02:38.344275Z","caller":"traceutil/trace.go:171","msg":"trace[867374918] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"353.976231ms","start":"2026-02-12T17:02:37.990286Z","end":"2026-02-12T17:02:38.344262Z","steps":["trace[867374918] 'process raft request' (duration: 353.607632ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.344309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:37.99027Z","time spent":"354.023553ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:38.344369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.861818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:38.344405Z","caller":"traceutil/trace.go:171","msg":"trace[369192496] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:981; }","duration":"392.897539ms","start":"2026-02-12T17:02:37.951487Z","end":"2026-02-12T17:02:38.344385Z","steps":["trace[369192496] 'agreement among raft nodes before linearized reading' (duration: 392.862438ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.344424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:37.95148Z","time spent":"392.94098ms","remote":"127.0.0.1:58624","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-12T17:02:38.344634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.477839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-12T17:02:38.34473Z","caller":"traceutil/trace.go:171","msg":"trace[1813886104] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:981; }","duration":"392.597681ms","start":"2026-02-12T17:02:37.952112Z","end":"2026-02-12T17:02:38.34471Z","steps":["trace[1813886104] 'agreement among raft nodes before linearized reading' (duration: 392.341295ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.344778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:37.952107Z","time spent":"392.661002ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:38.344901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.109068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-ssbfb\" ","response":"range_response_count:1 size:10924"} {"level":"info","ts":"2026-02-12T17:02:38.344924Z","caller":"traceutil/trace.go:171","msg":"trace[239785356] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-ssbfb; range_end:; response_count:1; response_revision:981; }","duration":"222.169819ms","start":"2026-02-12T17:02:38.122747Z","end":"2026-02-12T17:02:38.344917Z","steps":["trace[239785356] 'agreement among raft nodes before linearized reading' (duration: 222.080278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.345044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.817286ms","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-12T17:02:38.345099Z","caller":"traceutil/trace.go:171","msg":"trace[1637645892] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:981; }","duration":"392.897307ms","start":"2026-02-12T17:02:37.952192Z","end":"2026-02-12T17:02:38.345089Z","steps":["trace[1637645892] 'agreement among raft nodes before linearized reading' (duration: 392.784445ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:38.34513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:37.952157Z","time spent":"392.965179ms","remote":"127.0.0.1:58910","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-12T17:02:38.857691Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955498597,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:02:38.998337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.803738ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:38.998426Z","caller":"traceutil/trace.go:171","msg":"trace[1053738079] transaction","detail":"{read_only:false; response_revision:982; number_of_response:1; }","duration":"642.258006ms","start":"2026-02-12T17:02:38.356151Z","end":"2026-02-12T17:02:38.998409Z","steps":["trace[1053738079] 'process raft request' (duration: 517.304946ms)","trace[1053738079] 'compare' (duration: 123.803015ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:38.998482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:38.35613Z","time spent":"642.326928ms","remote":"127.0.0.1:58910","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-12T17:02:39.061742Z","caller":"traceutil/trace.go:171","msg":"trace[96444583] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"704.565848ms","start":"2026-02-12T17:02:38.35716Z","end":"2026-02-12T17:02:39.061725Z","steps":["trace[96444583] 'process raft request' (duration: 704.426835ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.061857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:38.357135Z","time spent":"704.658291ms","remote":"127.0.0.1:58826","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11172,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:39.062417Z","caller":"traceutil/trace.go:171","msg":"trace[979200030] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"703.858243ms","start":"2026-02-12T17:02:38.358537Z","end":"2026-02-12T17:02:39.062395Z","steps":["trace[979200030] 'process raft request' (duration: 703.150697ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.062555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:38.358528Z","time spent":"703.947164ms","remote":"127.0.0.1:58910","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-12T17:02:39.413317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.359543ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-12T17:02:39.413533Z","caller":"traceutil/trace.go:171","msg":"trace[1811546320] linearizableReadLoop","detail":"{readStateIndex:1023; appliedIndex:1019; }","duration":"1.056190478s","start":"2026-02-12T17:02:38.357331Z","end":"2026-02-12T17:02:39.413521Z","steps":["trace[1811546320] 'read index received' (duration: 516.138291ms)","trace[1811546320] 'applied index is now lower than readState.Index' (duration: 540.051147ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:39.413605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.056264869s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:39.413636Z","caller":"traceutil/trace.go:171","msg":"trace[1175942235] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:984; }","duration":"1.05632164s","start":"2026-02-12T17:02:38.357306Z","end":"2026-02-12T17:02:39.413628Z","steps":["trace[1175942235] 'agreement among raft nodes before linearized reading' (duration: 1.056250229s)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.413663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:38.357299Z","time spent":"1.056357541s","remote":"127.0.0.1:58636","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-12T17:02:39.415643Z","caller":"traceutil/trace.go:171","msg":"trace[1418109851] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"339.656706ms","start":"2026-02-12T17:02:39.075979Z","end":"2026-02-12T17:02:39.415636Z","steps":["trace[1418109851] 'process raft request' (duration: 339.552013ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.415935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:39.075962Z","time spent":"339.705566ms","remote":"127.0.0.1:58804","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":914,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:39.418886Z","caller":"traceutil/trace.go:171","msg":"trace[1253857060] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"245.271688ms","start":"2026-02-12T17:02:39.173595Z","end":"2026-02-12T17:02:39.418867Z","steps":["trace[1253857060] 'process raft request' (duration: 245.231857ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:39.419197Z","caller":"traceutil/trace.go:171","msg":"trace[1598071283] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"343.04352ms","start":"2026-02-12T17:02:39.076146Z","end":"2026-02-12T17:02:39.41919Z","steps":["trace[1598071283] 'process raft request' (duration: 342.534529ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.419275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:39.076142Z","time spent":"343.070401ms","remote":"127.0.0.1:58920","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1507,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:39.419438Z","caller":"traceutil/trace.go:171","msg":"trace[1520419013] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"341.63151ms","start":"2026-02-12T17:02:39.0778Z","end":"2026-02-12T17:02:39.419432Z","steps":["trace[1520419013] 'process raft request' (duration: 340.985276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:39.419472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:39.077793Z","time spent":"341.65947ms","remote":"127.0.0.1:59118","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:02:42.84614Z","caller":"traceutil/trace.go:171","msg":"trace[1658361710] linearizableReadLoop","detail":"{readStateIndex:1041; appliedIndex:1040; }","duration":"477.295486ms","start":"2026-02-12T17:02:42.368826Z","end":"2026-02-12T17:02:42.846121Z","steps":["trace[1658361710] 'read index received' (duration: 477.149593ms)","trace[1658361710] 'applied index is now lower than readState.Index' (duration: 144.943µs)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:02:42.846243Z","caller":"traceutil/trace.go:171","msg":"trace[1757491953] transaction","detail":"{read_only:false; response_revision:1002; number_of_response:1; }","duration":"485.007555ms","start":"2026-02-12T17:02:42.361226Z","end":"2026-02-12T17:02:42.846233Z","steps":["trace[1757491953] 'process raft request' (duration: 484.77102ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:42.846329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:42.361212Z","time spent":"485.049707ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:02:42.84642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.891721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:02:42.846488Z","caller":"traceutil/trace.go:171","msg":"trace[78788516] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1002; }","duration":"105.993674ms","start":"2026-02-12T17:02:42.740476Z","end":"2026-02-12T17:02:42.84647Z","steps":["trace[78788516] 'agreement among raft nodes before linearized reading' (duration: 105.887252ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:42.846623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"477.782507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-12T17:02:42.846645Z","caller":"traceutil/trace.go:171","msg":"trace[486863482] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1002; }","duration":"477.837839ms","start":"2026-02-12T17:02:42.368801Z","end":"2026-02-12T17:02:42.846639Z","steps":["trace[486863482] 'agreement among raft nodes before linearized reading' (duration: 477.745787ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:02:42.846661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:42.368788Z","time spent":"477.869619ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:02:45.550212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.574098ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:02:45.550318Z","caller":"traceutil/trace.go:171","msg":"trace[963065329] linearizableReadLoop","detail":"{readStateIndex:1058; appliedIndex:1057; }","duration":"293.698925ms","start":"2026-02-12T17:02:45.256603Z","end":"2026-02-12T17:02:45.550302Z","steps":["trace[963065329] 'read index received' (duration: 47.941µs)","trace[963065329] 'applied index is now lower than readState.Index' (duration: 293.649584ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:45.550725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.141475ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:472"} {"level":"info","ts":"2026-02-12T17:02:45.550769Z","caller":"traceutil/trace.go:171","msg":"trace[1168447194] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1018; }","duration":"294.220856ms","start":"2026-02-12T17:02:45.256535Z","end":"2026-02-12T17:02:45.550756Z","steps":["trace[1168447194] 'agreement among raft nodes before linearized reading' (duration: 294.056042ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:02:45.551229Z","caller":"traceutil/trace.go:171","msg":"trace[1865300050] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"443.942341ms","start":"2026-02-12T17:02:45.107272Z","end":"2026-02-12T17:02:45.551214Z","steps":["trace[1865300050] 'process raft request' (duration: 101.283299ms)","trace[1865300050] 'compare' (duration: 341.460136ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:02:45.551305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:02:45.107245Z","time spent":"444.023643ms","remote":"127.0.0.1:58910","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-12T17:02:50.385109Z","caller":"traceutil/trace.go:171","msg":"trace[217094722] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"216.306391ms","start":"2026-02-12T17:02:50.168783Z","end":"2026-02-12T17:02:50.385089Z","steps":["trace[217094722] 'process raft request' (duration: 216.142468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:05:03.98095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.385316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-12T17:05:03.981023Z","caller":"traceutil/trace.go:171","msg":"trace[1884894612] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1606; }","duration":"111.486167ms","start":"2026-02-12T17:05:03.869519Z","end":"2026-02-12T17:05:03.981005Z","steps":["trace[1884894612] 'range keys from in-memory index tree' (duration: 111.262733ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:05:03.981345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.84073ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T17:05:03.981387Z","caller":"traceutil/trace.go:171","msg":"trace[1266379513] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1606; }","duration":"115.882481ms","start":"2026-02-12T17:05:03.865494Z","end":"2026-02-12T17:05:03.981377Z","steps":["trace[1266379513] 'range keys from in-memory index tree' (duration: 115.728318ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:06:05.805051Z","caller":"traceutil/trace.go:171","msg":"trace[144411010] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"142.340448ms","start":"2026-02-12T17:06:05.66266Z","end":"2026-02-12T17:06:05.805001Z","steps":["trace[144411010] 'process raft request' (duration: 142.103363ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:06:11.610708Z","caller":"traceutil/trace.go:171","msg":"trace[2005342394] transaction","detail":"{read_only:false; response_revision:1910; number_of_response:1; }","duration":"203.243847ms","start":"2026-02-12T17:06:11.407436Z","end":"2026-02-12T17:06:11.610679Z","steps":["trace[2005342394] 'process raft request' (duration: 203.009572ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:13.68756Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955500955,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:06:14.077401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.756624ms","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-12T17:06:14.077533Z","caller":"traceutil/trace.go:171","msg":"trace[1096287094] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1917; }","duration":"323.923587ms","start":"2026-02-12T17:06:13.75359Z","end":"2026-02-12T17:06:14.077513Z","steps":["trace[1096287094] 'range keys from in-memory index tree' (duration: 323.741743ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:06:14.078294Z","caller":"traceutil/trace.go:171","msg":"trace[1122375859] linearizableReadLoop","detail":"{readStateIndex:2000; appliedIndex:1999; }","duration":"891.481006ms","start":"2026-02-12T17:06:13.18678Z","end":"2026-02-12T17:06:14.078261Z","steps":["trace[1122375859] 'read index received' (duration: 889.651356ms)","trace[1122375859] 'applied index is now lower than readState.Index' (duration: 1.82819ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:06:14.078564Z","caller":"traceutil/trace.go:171","msg":"trace[732646602] transaction","detail":"{read_only:false; response_revision:1918; number_of_response:1; }","duration":"894.00447ms","start":"2026-02-12T17:06:13.184547Z","end":"2026-02-12T17:06:14.078552Z","steps":["trace[732646602] 'process raft request' (duration: 891.947736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.078661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:13.184525Z","time spent":"894.067652ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:06:14.078901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.120779ms","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-12T17:06:14.078932Z","caller":"traceutil/trace.go:171","msg":"trace[1237427274] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1918; }","duration":"892.180701ms","start":"2026-02-12T17:06:13.186742Z","end":"2026-02-12T17:06:14.078922Z","steps":["trace[1237427274] 'agreement among raft nodes before linearized reading' (duration: 892.068278ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.07896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:13.186722Z","time spent":"892.232251ms","remote":"127.0.0.1:58910","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-12T17:06:14.079145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.64464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.17\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-12T17:06:14.079189Z","caller":"traceutil/trace.go:171","msg":"trace[580977417] range","detail":"{range_begin:/registry/masterleases/199.19.213.17; range_end:; response_count:1; response_revision:1918; }","duration":"560.721622ms","start":"2026-02-12T17:06:13.518459Z","end":"2026-02-12T17:06:14.079181Z","steps":["trace[580977417] 'agreement among raft nodes before linearized reading' (duration: 560.62418ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.079642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:13.518439Z","time spent":"561.189741ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.17\" "} {"level":"warn","ts":"2026-02-12T17:06:14.079837Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.835132ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:06:14.079866Z","caller":"traceutil/trace.go:171","msg":"trace[1064260036] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1918; }","duration":"126.962125ms","start":"2026-02-12T17:06:13.952894Z","end":"2026-02-12T17:06:14.079856Z","steps":["trace[1064260036] 'agreement among raft nodes before linearized reading' (duration: 126.915574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.079974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.38276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-02-12T17:06:14.080024Z","caller":"traceutil/trace.go:171","msg":"trace[590673814] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1918; }","duration":"363.468672ms","start":"2026-02-12T17:06:13.716536Z","end":"2026-02-12T17:06:14.080005Z","steps":["trace[590673814] 'agreement among raft nodes before linearized reading' (duration: 363.329069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.080041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.632704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:527"} {"level":"warn","ts":"2026-02-12T17:06:14.080056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:13.716512Z","time spent":"363.535354ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-02-12T17:06:14.080073Z","caller":"traceutil/trace.go:171","msg":"trace[134519087] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1918; }","duration":"397.689586ms","start":"2026-02-12T17:06:13.682372Z","end":"2026-02-12T17:06:14.080062Z","steps":["trace[134519087] 'agreement among raft nodes before linearized reading' (duration: 397.602414ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.080102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:13.682356Z","time spent":"397.739946ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-02-12T17:06:14.080273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.696933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-02-12T17:06:14.080306Z","caller":"traceutil/trace.go:171","msg":"trace[702506280] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1918; }","duration":"215.754584ms","start":"2026-02-12T17:06:13.864541Z","end":"2026-02-12T17:06:14.080295Z","steps":["trace[702506280] 'agreement among raft nodes before linearized reading' (duration: 215.666203ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.590094Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522831212955500958,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-12T17:06:14.694854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.089279Z","time spent":"605.571051ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-12T17:06:14.997578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.613063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:06:14.997848Z","caller":"traceutil/trace.go:171","msg":"trace[1688271882] transaction","detail":"{read_only:false; response_revision:1919; number_of_response:1; }","duration":"905.393934ms","start":"2026-02-12T17:06:14.092421Z","end":"2026-02-12T17:06:14.997814Z","steps":["trace[1688271882] 'process raft request' (duration: 746.445854ms)","trace[1688271882] 'compare' (duration: 158.46135ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:06:14.998005Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.092413Z","time spent":"905.515206ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:06:14.999141Z","caller":"traceutil/trace.go:171","msg":"trace[1821738286] transaction","detail":"{read_only:false; response_revision:1920; number_of_response:1; }","duration":"906.65763ms","start":"2026-02-12T17:06:14.092458Z","end":"2026-02-12T17:06:14.999115Z","steps":["trace[1821738286] 'process raft request' (duration: 905.21373ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:14.999221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.092454Z","time spent":"906.723652ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":512,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:06:15.004542Z","caller":"traceutil/trace.go:171","msg":"trace[135783055] linearizableReadLoop","detail":"{readStateIndex:2005; appliedIndex:2000; }","duration":"914.832765ms","start":"2026-02-12T17:06:14.089695Z","end":"2026-02-12T17:06:15.004527Z","steps":["trace[135783055] 'read index received' (duration: 589.562218ms)","trace[135783055] 'applied index is now lower than readState.Index' (duration: 325.269877ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:06:15.004718Z","caller":"traceutil/trace.go:171","msg":"trace[247672126] transaction","detail":"{read_only:false; response_revision:1922; number_of_response:1; }","duration":"910.710247ms","start":"2026-02-12T17:06:14.093993Z","end":"2026-02-12T17:06:15.004703Z","steps":["trace[247672126] 'process raft request' (duration: 910.474542ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.004748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.046479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:06:15.004769Z","caller":"traceutil/trace.go:171","msg":"trace[1127863281] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1922; }","duration":"915.07396ms","start":"2026-02-12T17:06:14.08969Z","end":"2026-02-12T17:06:15.004764Z","steps":["trace[1127863281] 'agreement among raft nodes before linearized reading' (duration: 915.032999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.004789Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.089604Z","time spent":"915.181193ms","remote":"127.0.0.1:58636","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-12T17:06:15.004792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.093987Z","time spent":"910.766688ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":494,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-12T17:06:15.004941Z","caller":"traceutil/trace.go:171","msg":"trace[163058016] transaction","detail":"{read_only:false; response_revision:1921; number_of_response:1; }","duration":"911.243509ms","start":"2026-02-12T17:06:14.093689Z","end":"2026-02-12T17:06:15.004933Z","steps":["trace[163058016] 'process raft request' (duration: 904.069896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.004978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.093666Z","time spent":"911.2898ms","remote":"127.0.0.1:58910","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-12T17:06:15.033004Z","caller":"traceutil/trace.go:171","msg":"trace[1193809335] transaction","detail":"{read_only:false; response_revision:1923; number_of_response:1; }","duration":"336.770533ms","start":"2026-02-12T17:06:14.696214Z","end":"2026-02-12T17:06:15.032985Z","steps":["trace[1193809335] 'process raft request' (duration: 336.661651ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.033136Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.696197Z","time spent":"336.869296ms","remote":"127.0.0.1:58656","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:06:15.033675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.253433ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-12T17:06:15.033716Z","caller":"traceutil/trace.go:171","msg":"trace[563850454] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1923; }","duration":"107.337375ms","start":"2026-02-12T17:06:14.926368Z","end":"2026-02-12T17:06:15.033705Z","steps":["trace[563850454] 'agreement among raft nodes before linearized reading' (duration: 107.155001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.041425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"738.597396ms","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-12T17:06:15.041465Z","caller":"traceutil/trace.go:171","msg":"trace[1466982522] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1923; }","duration":"738.667868ms","start":"2026-02-12T17:06:14.302787Z","end":"2026-02-12T17:06:15.041455Z","steps":["trace[1466982522] 'agreement among raft nodes before linearized reading' (duration: 738.569736ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.041487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.302754Z","time spent":"738.728749ms","remote":"127.0.0.1:58910","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-12T17:06:15.041709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.438212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-02-12T17:06:15.04173Z","caller":"traceutil/trace.go:171","msg":"trace[216718266] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1923; }","duration":"426.499712ms","start":"2026-02-12T17:06:14.615224Z","end":"2026-02-12T17:06:15.041724Z","steps":["trace[216718266] 'agreement among raft nodes before linearized reading' (duration: 426.429061ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.041747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.615203Z","time spent":"426.540283ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-12T17:06:15.045411Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"545.992647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-02-12T17:06:15.045591Z","caller":"traceutil/trace.go:171","msg":"trace[1944809628] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1923; }","duration":"546.164661ms","start":"2026-02-12T17:06:14.49935Z","end":"2026-02-12T17:06:15.045515Z","steps":["trace[1944809628] 'agreement among raft nodes before linearized reading' (duration: 545.907325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.045598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"953.283957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-12T17:06:15.045633Z","caller":"traceutil/trace.go:171","msg":"trace[2055153631] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:1923; }","duration":"953.332148ms","start":"2026-02-12T17:06:14.092292Z","end":"2026-02-12T17:06:15.045624Z","steps":["trace[2055153631] 'agreement among raft nodes before linearized reading' (duration: 953.272757ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.045657Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.092287Z","time spent":"953.365399ms","remote":"127.0.0.1:58792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-12T17:06:15.045634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:14.499326Z","time spent":"546.299244ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-12T17:06:15.416098Z","caller":"traceutil/trace.go:171","msg":"trace[268707616] transaction","detail":"{read_only:false; response_revision:1924; number_of_response:1; }","duration":"361.569363ms","start":"2026-02-12T17:06:15.05451Z","end":"2026-02-12T17:06:15.41608Z","steps":["trace[268707616] 'process raft request' (duration: 361.43981ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.417225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.054499Z","time spent":"361.743186ms","remote":"127.0.0.1:58910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-12T17:06:15.728632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.248848ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-12T17:06:15.73Z","caller":"traceutil/trace.go:171","msg":"trace[58329970] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2007; }","duration":"674.28684ms","start":"2026-02-12T17:06:15.05569Z","end":"2026-02-12T17:06:15.729977Z","steps":["trace[58329970] 'read index received' (duration: 360.641462ms)","trace[58329970] 'applied index is now lower than readState.Index' (duration: 313.641857ms)"],"step_count":2} {"level":"info","ts":"2026-02-12T17:06:15.730151Z","caller":"traceutil/trace.go:171","msg":"trace[1263552713] transaction","detail":"{read_only:false; response_revision:1925; number_of_response:1; }","duration":"674.623907ms","start":"2026-02-12T17:06:15.055512Z","end":"2026-02-12T17:06:15.730136Z","steps":["trace[1263552713] 'process raft request' (duration: 482.791435ms)","trace[1263552713] 'compare' (duration: 190.156457ms)"],"step_count":2} {"level":"warn","ts":"2026-02-12T17:06:15.730432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.055504Z","time spent":"674.75912ms","remote":"127.0.0.1:58910","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-12T17:06:15.730824Z","caller":"traceutil/trace.go:171","msg":"trace[1312412072] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"675.253591ms","start":"2026-02-12T17:06:15.055555Z","end":"2026-02-12T17:06:15.730808Z","steps":["trace[1312412072] 'process raft request' (duration: 673.890671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.730902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.055551Z","time spent":"675.313832ms","remote":"127.0.0.1:58910","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-12T17:06:15.731041Z","caller":"traceutil/trace.go:171","msg":"trace[42205648] transaction","detail":"{read_only:false; response_revision:1927; number_of_response:1; }","duration":"674.300921ms","start":"2026-02-12T17:06:15.056731Z","end":"2026-02-12T17:06:15.731031Z","steps":["trace[42205648] 'process raft request' (duration: 672.805588ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.731098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.056724Z","time spent":"674.344752ms","remote":"127.0.0.1:58910","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-12T17:06:15.731245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"675.547717ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-12T17:06:15.731283Z","caller":"traceutil/trace.go:171","msg":"trace[1938760960] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1927; }","duration":"675.592538ms","start":"2026-02-12T17:06:15.05568Z","end":"2026-02-12T17:06:15.731272Z","steps":["trace[1938760960] 'agreement among raft nodes before linearized reading' (duration: 675.524386ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.73131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.055675Z","time spent":"675.62891ms","remote":"127.0.0.1:58920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-02-12T17:06:15.731478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.559369ms","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-12T17:06:15.731511Z","caller":"traceutil/trace.go:171","msg":"trace[5590718] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1927; }","duration":"315.629311ms","start":"2026-02-12T17:06:15.415874Z","end":"2026-02-12T17:06:15.731504Z","steps":["trace[5590718] 'agreement among raft nodes before linearized reading' (duration: 315.57724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-12T17:06:15.731541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-12T17:06:15.415856Z","time spent":"315.677261ms","remote":"127.0.0.1:59094","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":"info","ts":"2026-02-12T17:06:56.13208Z","caller":"traceutil/trace.go:171","msg":"trace[936452927] transaction","detail":"{read_only:false; response_revision:2099; number_of_response:1; }","duration":"106.744503ms","start":"2026-02-12T17:06:56.025323Z","end":"2026-02-12T17:06:56.132067Z","steps":["trace[936452927] 'process raft request' (duration: 104.401352ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:06:57.410648Z","caller":"traceutil/trace.go:171","msg":"trace[1358660639] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"136.54586ms","start":"2026-02-12T17:06:57.274079Z","end":"2026-02-12T17:06:57.410625Z","steps":["trace[1358660639] 'process raft request' (duration: 136.357786ms)"],"step_count":1} {"level":"info","ts":"2026-02-12T17:06:58.249428Z","caller":"traceutil/trace.go:171","msg":"trace[60551303] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"109.929441ms","start":"2026-02-12T17:06:58.139476Z","end":"2026-02-12T17:06:58.249406Z","steps":["trace[60551303] 'process raft request' (duration: 82.638998ms)","trace[60551303] 'compare' (duration: 26.69372ms)"],"step_count":2}