{"level":"warn","ts":"2026-05-27T19:43:31.634452Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:43:31.634552Z","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-05-27T19:43:31.63464Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-27T19:43:31.634656Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.17:2380"]} {"level":"info","ts":"2026-05-27T19:43:31.634684Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:43:31.635537Z","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-05-27T19:43:31.635631Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.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-05-27T19:43:31.640524Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.612598ms"} {"level":"info","ts":"2026-05-27T19:43:31.646773Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"27c4b87f3ea730e3","cluster-id":"cdce983f4a7486fe"} {"level":"info","ts":"2026-05-27T19:43:31.646837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=()"} {"level":"info","ts":"2026-05-27T19:43:31.646866Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 0"} {"level":"info","ts":"2026-05-27T19:43:31.646915Z","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-05-27T19:43:31.646927Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became follower at term 1"} {"level":"info","ts":"2026-05-27T19:43:31.646988Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"warn","ts":"2026-05-27T19:43:31.651608Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-27T19:43:31.653123Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-27T19:43:31.65505Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-05-27T19:43:31.657284Z","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-05-27T19:43:31.659898Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:43:31.660039Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:43:31.660059Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-05-27T19:43:31.65984Z","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-05-27T19:43:31.664846Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-05-27T19:43:31.66486Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 switched to configuration voters=(2865618119565979875)"} {"level":"info","ts":"2026-05-27T19:43:31.665006Z","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-05-27T19:43:31.665054Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-05-27T19:43:31.665075Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.17:2380"} {"level":"info","ts":"2026-05-27T19:43:31.665086Z","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-05-27T19:43:31.665132Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-27T19:43:32.048084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 is starting a new election at term 1"} {"level":"info","ts":"2026-05-27T19:43:32.048153Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became pre-candidate at term 1"} {"level":"info","ts":"2026-05-27T19:43:32.048195Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgPreVoteResp from 27c4b87f3ea730e3 at term 1"} {"level":"info","ts":"2026-05-27T19:43:32.048217Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became candidate at term 2"} {"level":"info","ts":"2026-05-27T19:43:32.048231Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 received MsgVoteResp from 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-05-27T19:43:32.048249Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"27c4b87f3ea730e3 became leader at term 2"} {"level":"info","ts":"2026-05-27T19:43:32.048272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 27c4b87f3ea730e3 elected leader 27c4b87f3ea730e3 at term 2"} {"level":"info","ts":"2026-05-27T19:43:32.049344Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:32.050033Z","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-05-27T19:43:32.050062Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:43:32.050079Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-27T19:43:32.050377Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-27T19:43:32.050453Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-27T19:43:32.050687Z","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-05-27T19:43:32.050799Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:32.050837Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-27T19:43:32.053036Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.17:2379"} {"level":"info","ts":"2026-05-27T19:43:32.053766Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-27T19:43:34.458858Z","caller":"traceutil/trace.go:171","msg":"trace[890034189] transaction","detail":"{read_only:false; response_revision:114; number_of_response:1; }","duration":"102.735071ms","start":"2026-05-27T19:43:34.356094Z","end":"2026-05-27T19:43:34.45883Z","steps":["trace[890034189] 'process raft request' (duration: 88.960447ms)","trace[890034189] 'compare' (duration: 13.645591ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:34.67166Z","caller":"traceutil/trace.go:171","msg":"trace[438244189] transaction","detail":"{read_only:false; response_revision:119; number_of_response:1; }","duration":"130.92591ms","start":"2026-05-27T19:43:34.540702Z","end":"2026-05-27T19:43:34.671628Z","steps":["trace[438244189] 'process raft request' (duration: 104.877878ms)","trace[438244189] 'compare' (duration: 25.847919ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:43.073804Z","caller":"traceutil/trace.go:171","msg":"trace[624213716] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"122.736243ms","start":"2026-05-27T19:43:42.95104Z","end":"2026-05-27T19:43:43.073777Z","steps":["trace[624213716] 'process raft request' (duration: 77.011092ms)","trace[624213716] 'compare' (duration: 45.604889ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:44.508556Z","caller":"traceutil/trace.go:171","msg":"trace[471878921] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"441.780976ms","start":"2026-05-27T19:43:44.066751Z","end":"2026-05-27T19:43:44.508532Z","steps":["trace[471878921] 'process raft request' (duration: 441.608203ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:44.509433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:44.066724Z","time spent":"441.914488ms","remote":"127.0.0.1:50314","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-05-27T19:43:45.589458Z","caller":"traceutil/trace.go:171","msg":"trace[1188663525] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"123.285234ms","start":"2026-05-27T19:43:45.466152Z","end":"2026-05-27T19:43:45.589437Z","steps":["trace[1188663525] 'process raft request' (duration: 123.125141ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:47.379837Z","caller":"traceutil/trace.go:171","msg":"trace[568713552] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"172.361588ms","start":"2026-05-27T19:43:47.207453Z","end":"2026-05-27T19:43:47.379815Z","steps":["trace[568713552] 'process raft request' (duration: 172.281676ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:47.380019Z","caller":"traceutil/trace.go:171","msg":"trace[653539379] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"220.693879ms","start":"2026-05-27T19:43:47.159297Z","end":"2026-05-27T19:43:47.379991Z","steps":["trace[653539379] 'process raft request' (duration: 165.771001ms)","trace[653539379] 'compare' (duration: 54.449298ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:56.709925Z","caller":"traceutil/trace.go:171","msg":"trace[1316692326] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"129.578014ms","start":"2026-05-27T19:43:56.580319Z","end":"2026-05-27T19:43:56.709897Z","steps":["trace[1316692326] 'process raft request' (duration: 129.401251ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:56.772124Z","caller":"traceutil/trace.go:171","msg":"trace[306393219] transaction","detail":"{read_only:false; response_revision:436; number_of_response:1; }","duration":"186.102783ms","start":"2026-05-27T19:43:56.585998Z","end":"2026-05-27T19:43:56.772101Z","steps":["trace[306393219] 'process raft request' (duration: 185.97939ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:56.889136Z","caller":"traceutil/trace.go:171","msg":"trace[1652020777] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"110.102969ms","start":"2026-05-27T19:43:56.779017Z","end":"2026-05-27T19:43:56.88912Z","steps":["trace[1652020777] 'process raft request' (duration: 59.286281ms)","trace[1652020777] 'compare' (duration: 50.496152ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:57.100381Z","caller":"traceutil/trace.go:171","msg":"trace[519568294] transaction","detail":"{read_only:false; response_revision:447; number_of_response:1; }","duration":"161.259974ms","start":"2026-05-27T19:43:56.939102Z","end":"2026-05-27T19:43:57.100362Z","steps":["trace[519568294] 'process raft request' (duration: 161.219513ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:57.100709Z","caller":"traceutil/trace.go:171","msg":"trace[788049566] transaction","detail":"{read_only:false; response_revision:443; number_of_response:1; }","duration":"163.956556ms","start":"2026-05-27T19:43:56.936742Z","end":"2026-05-27T19:43:57.100699Z","steps":["trace[788049566] 'process raft request' (duration: 112.630389ms)","trace[788049566] 'compare' (duration: 50.678095ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:57.100829Z","caller":"traceutil/trace.go:171","msg":"trace[286638051] transaction","detail":"{read_only:false; response_revision:444; number_of_response:1; }","duration":"164.001198ms","start":"2026-05-27T19:43:56.93682Z","end":"2026-05-27T19:43:57.100821Z","steps":["trace[286638051] 'process raft request' (duration: 163.388686ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:57.101067Z","caller":"traceutil/trace.go:171","msg":"trace[1076170192] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"163.796813ms","start":"2026-05-27T19:43:56.937261Z","end":"2026-05-27T19:43:57.101058Z","steps":["trace[1076170192] 'process raft request' (duration: 162.978737ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:57.101182Z","caller":"traceutil/trace.go:171","msg":"trace[827292054] transaction","detail":"{read_only:false; response_revision:446; number_of_response:1; }","duration":"162.331225ms","start":"2026-05-27T19:43:56.938844Z","end":"2026-05-27T19:43:57.101175Z","steps":["trace[827292054] 'process raft request' (duration: 161.446728ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:57.27628Z","caller":"traceutil/trace.go:171","msg":"trace[1172742844] linearizableReadLoop","detail":"{readStateIndex:461; appliedIndex:459; }","duration":"168.429053ms","start":"2026-05-27T19:43:57.107827Z","end":"2026-05-27T19:43:57.276256Z","steps":["trace[1172742844] 'read index received' (duration: 44.604679ms)","trace[1172742844] 'applied index is now lower than readState.Index' (duration: 123.823474ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:43:57.276429Z","caller":"traceutil/trace.go:171","msg":"trace[1528684442] transaction","detail":"{read_only:false; response_revision:449; number_of_response:1; }","duration":"170.106566ms","start":"2026-05-27T19:43:57.106285Z","end":"2026-05-27T19:43:57.276392Z","steps":["trace[1528684442] 'process raft request' (duration: 152.441285ms)","trace[1528684442] 'compare' (duration: 17.428356ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:57.2767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.532847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4195"} {"level":"info","ts":"2026-05-27T19:43:57.276799Z","caller":"traceutil/trace.go:171","msg":"trace[874078824] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:449; }","duration":"166.666919ms","start":"2026-05-27T19:43:57.110115Z","end":"2026-05-27T19:43:57.276782Z","steps":["trace[874078824] 'agreement among raft nodes before linearized reading' (duration: 166.488856ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:57.277285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.451322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-67659f764b\" ","response":"range_response_count:1 size:3810"} {"level":"info","ts":"2026-05-27T19:43:57.277323Z","caller":"traceutil/trace.go:171","msg":"trace[791240554] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-67659f764b; range_end:; response_count:1; response_revision:449; }","duration":"169.497854ms","start":"2026-05-27T19:43:57.107816Z","end":"2026-05-27T19:43:57.277313Z","steps":["trace[791240554] 'agreement among raft nodes before linearized reading' (duration: 168.73503ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:59.045491Z","caller":"traceutil/trace.go:171","msg":"trace[1581548282] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"104.685136ms","start":"2026-05-27T19:43:58.94077Z","end":"2026-05-27T19:43:59.045455Z","steps":["trace[1581548282] 'process raft request' (duration: 104.491782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:43:59.967453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.206799ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:43:59.967547Z","caller":"traceutil/trace.go:171","msg":"trace[1804937914] linearizableReadLoop","detail":"{readStateIndex:485; appliedIndex:484; }","duration":"427.473361ms","start":"2026-05-27T19:43:59.540058Z","end":"2026-05-27T19:43:59.967531Z","steps":["trace[1804937914] 'read index received' (duration: 46.014436ms)","trace[1804937914] 'applied index is now lower than readState.Index' (duration: 381.457935ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:59.967637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.586383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:43:59.967669Z","caller":"traceutil/trace.go:171","msg":"trace[1801987946] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:471; }","duration":"427.649224ms","start":"2026-05-27T19:43:59.540011Z","end":"2026-05-27T19:43:59.96766Z","steps":["trace[1801987946] 'agreement among raft nodes before linearized reading' (duration: 427.599343ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:43:59.967649Z","caller":"traceutil/trace.go:171","msg":"trace[1333509088] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"917.590966ms","start":"2026-05-27T19:43:59.050017Z","end":"2026-05-27T19:43:59.967608Z","steps":["trace[1333509088] 'process raft request' (duration: 536.02669ms)","trace[1333509088] 'compare' (duration: 381.091337ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:43:59.967708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:59.539993Z","time spent":"427.708255ms","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:43:59.967792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:43:59.05Z","time spent":"917.720908ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:07.399339Z","caller":"traceutil/trace.go:171","msg":"trace[1016552350] linearizableReadLoop","detail":"{readStateIndex:504; appliedIndex:503; }","duration":"160.424379ms","start":"2026-05-27T19:44:07.238891Z","end":"2026-05-27T19:44:07.399316Z","steps":["trace[1016552350] 'read index received' (duration: 160.200005ms)","trace[1016552350] 'applied index is now lower than readState.Index' (duration: 223.204µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:07.399434Z","caller":"traceutil/trace.go:171","msg":"trace[1163367267] transaction","detail":"{read_only:false; response_revision:488; number_of_response:1; }","duration":"243.999157ms","start":"2026-05-27T19:44:07.155401Z","end":"2026-05-27T19:44:07.3994Z","steps":["trace[1163367267] 'process raft request' (duration: 243.776063ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:07.399473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.568371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:3 size:12669"} {"level":"info","ts":"2026-05-27T19:44:07.399521Z","caller":"traceutil/trace.go:171","msg":"trace[1835243037] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:3; response_revision:488; }","duration":"160.675813ms","start":"2026-05-27T19:44:07.238834Z","end":"2026-05-27T19:44:07.399509Z","steps":["trace[1835243037] 'agreement among raft nodes before linearized reading' (duration: 160.566101ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:07.645368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.595362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:07.645452Z","caller":"traceutil/trace.go:171","msg":"trace[1987618767] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:488; }","duration":"104.717065ms","start":"2026-05-27T19:44:07.540718Z","end":"2026-05-27T19:44:07.645435Z","steps":["trace[1987618767] 'range keys from in-memory index tree' (duration: 104.519631ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:08.166057Z","caller":"traceutil/trace.go:171","msg":"trace[289711912] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"161.190053ms","start":"2026-05-27T19:44:08.004842Z","end":"2026-05-27T19:44:08.166032Z","steps":["trace[289711912] 'process raft request' (duration: 161.03352ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:08.793655Z","caller":"traceutil/trace.go:171","msg":"trace[1441369535] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"467.726504ms","start":"2026-05-27T19:44:08.325905Z","end":"2026-05-27T19:44:08.793631Z","steps":["trace[1441369535] 'process raft request' (duration: 467.410787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:08.794262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:08.32588Z","time spent":"467.850028ms","remote":"127.0.0.1:50150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":317296,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:09.32924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.77039ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:09.329482Z","caller":"traceutil/trace.go:171","msg":"trace[2111503597] linearizableReadLoop","detail":"{readStateIndex:510; appliedIndex:508; }","duration":"412.705095ms","start":"2026-05-27T19:44:08.916759Z","end":"2026-05-27T19:44:09.329464Z","steps":["trace[2111503597] 'read index received' (duration: 2.675311ms)","trace[2111503597] 'applied index is now lower than readState.Index' (duration: 410.028024ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:09.32966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.90154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:44:09.329699Z","caller":"traceutil/trace.go:171","msg":"trace[344130722] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:494; }","duration":"412.980081ms","start":"2026-05-27T19:44:08.916707Z","end":"2026-05-27T19:44:09.329687Z","steps":["trace[344130722] 'agreement among raft nodes before linearized reading' (duration: 412.845288ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:09.32976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:08.916686Z","time spent":"413.041152ms","remote":"127.0.0.1:50314","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-05-27T19:44:09.32979Z","caller":"traceutil/trace.go:171","msg":"trace[333985730] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"505.261906ms","start":"2026-05-27T19:44:08.824475Z","end":"2026-05-27T19:44:09.329737Z","steps":["trace[333985730] 'process raft request' (duration: 504.873879ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:09.329782Z","caller":"traceutil/trace.go:171","msg":"trace[1730945455] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"505.231606ms","start":"2026-05-27T19:44:08.824474Z","end":"2026-05-27T19:44:09.329706Z","steps":["trace[1730945455] 'process raft request' (duration: 94.918426ms)","trace[1730945455] 'compare' (duration: 409.517055ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:09.329955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:08.824455Z","time spent":"505.427981ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:09.329984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:08.824455Z","time spent":"505.433691ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":505,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:10.315305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:09.647435Z","time spent":"667.861237ms","remote":"127.0.0.1:36478","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-05-27T19:44:10.315914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.100605ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:10.316002Z","caller":"traceutil/trace.go:171","msg":"trace[1151269793] linearizableReadLoop","detail":"{readStateIndex:512; appliedIndex:510; }","duration":"912.08962ms","start":"2026-05-27T19:44:09.403895Z","end":"2026-05-27T19:44:10.315984Z","steps":["trace[1151269793] 'read index received' (duration: 2.855745ms)","trace[1151269793] 'applied index is now lower than readState.Index' (duration: 909.232355ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:10.316104Z","caller":"traceutil/trace.go:171","msg":"trace[915996281] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"979.903815ms","start":"2026-05-27T19:44:09.336167Z","end":"2026-05-27T19:44:10.316071Z","steps":["trace[915996281] 'process raft request' (duration: 382.564805ms)","trace[915996281] 'compare' (duration: 596.970393ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:10.316176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.278033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-27T19:44:10.316221Z","caller":"traceutil/trace.go:171","msg":"trace[1629890726] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:496; }","duration":"912.378025ms","start":"2026-05-27T19:44:09.403829Z","end":"2026-05-27T19:44:10.316207Z","steps":["trace[1629890726] 'agreement among raft nodes before linearized reading' (duration: 912.251233ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:10.316229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:09.336145Z","time spent":"980.025178ms","remote":"127.0.0.1:50152","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6516,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:10.316264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:09.403794Z","time spent":"912.458997ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:44:10.316526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.494738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:44:10.316608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.649225ms","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-05-27T19:44:10.316625Z","caller":"traceutil/trace.go:171","msg":"trace[83818591] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:496; }","duration":"777.59319ms","start":"2026-05-27T19:44:09.539006Z","end":"2026-05-27T19:44:10.316599Z","steps":["trace[83818591] 'agreement among raft nodes before linearized reading' (duration: 777.435397ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:10.31664Z","caller":"traceutil/trace.go:171","msg":"trace[1322556006] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:496; }","duration":"144.721485ms","start":"2026-05-27T19:44:10.171909Z","end":"2026-05-27T19:44:10.31663Z","steps":["trace[1322556006] 'agreement among raft nodes before linearized reading' (duration: 144.644144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:10.316667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:09.53895Z","time spent":"777.709482ms","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:44:12.397286Z","caller":"traceutil/trace.go:171","msg":"trace[115232188] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"320.293085ms","start":"2026-05-27T19:44:12.076963Z","end":"2026-05-27T19:44:12.397256Z","steps":["trace[115232188] 'process raft request' (duration: 319.907878ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:12.397568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:12.076941Z","time spent":"320.50928ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:44:12.403404Z","caller":"traceutil/trace.go:171","msg":"trace[1117349967] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"325.834733ms","start":"2026-05-27T19:44:12.077546Z","end":"2026-05-27T19:44:12.40338Z","steps":["trace[1117349967] 'process raft request' (duration: 325.658069ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.403506Z","caller":"traceutil/trace.go:171","msg":"trace[988971627] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"302.701507ms","start":"2026-05-27T19:44:12.100767Z","end":"2026-05-27T19:44:12.403469Z","steps":["trace[988971627] 'process raft request' (duration: 302.556445ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.403545Z","caller":"traceutil/trace.go:171","msg":"trace[1895308554] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"301.957263ms","start":"2026-05-27T19:44:12.101564Z","end":"2026-05-27T19:44:12.403521Z","steps":["trace[1895308554] 'process raft request' (duration: 301.81161ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:12.403674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:12.101556Z","time spent":"302.080935ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:12.40372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:12.100749Z","time spent":"302.84358ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:44:12.403801Z","caller":"traceutil/trace.go:171","msg":"trace[688637366] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"293.403159ms","start":"2026-05-27T19:44:12.110388Z","end":"2026-05-27T19:44:12.403791Z","steps":["trace[688637366] 'process raft request' (duration: 293.087313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:12.40393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:12.077516Z","time spent":"325.953475ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:44:12.403964Z","caller":"traceutil/trace.go:171","msg":"trace[1656218925] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"289.159876ms","start":"2026-05-27T19:44:12.114793Z","end":"2026-05-27T19:44:12.403953Z","steps":["trace[1656218925] 'process raft request' (duration: 288.727348ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.404026Z","caller":"traceutil/trace.go:171","msg":"trace[1661808852] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"285.946774ms","start":"2026-05-27T19:44:12.118064Z","end":"2026-05-27T19:44:12.404011Z","steps":["trace[1661808852] 'process raft request' (duration: 285.516916ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.403416Z","caller":"traceutil/trace.go:171","msg":"trace[831274464] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"325.756712ms","start":"2026-05-27T19:44:12.077644Z","end":"2026-05-27T19:44:12.403401Z","steps":["trace[831274464] 'process raft request' (duration: 325.64609ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.40412Z","caller":"traceutil/trace.go:171","msg":"trace[1366826292] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"270.352264ms","start":"2026-05-27T19:44:12.133759Z","end":"2026-05-27T19:44:12.404111Z","steps":["trace[1366826292] 'process raft request' (duration: 270.1413ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:12.404182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:12.077638Z","time spent":"326.502916ms","remote":"127.0.0.1:50086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:44:12.40394Z","caller":"traceutil/trace.go:171","msg":"trace[1133438940] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"298.867693ms","start":"2026-05-27T19:44:12.105044Z","end":"2026-05-27T19:44:12.403912Z","steps":["trace[1133438940] 'process raft request' (duration: 298.391785ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.404309Z","caller":"traceutil/trace.go:171","msg":"trace[508429020] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"250.691426ms","start":"2026-05-27T19:44:12.153593Z","end":"2026-05-27T19:44:12.404284Z","steps":["trace[508429020] 'process raft request' (duration: 250.408661ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:12.404329Z","caller":"traceutil/trace.go:171","msg":"trace[1503020823] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"148.665052ms","start":"2026-05-27T19:44:12.255641Z","end":"2026-05-27T19:44:12.404306Z","steps":["trace[1503020823] 'process raft request' (duration: 148.464138ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:13.479207Z","caller":"traceutil/trace.go:171","msg":"trace[414334200] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"214.924337ms","start":"2026-05-27T19:44:13.264255Z","end":"2026-05-27T19:44:13.479179Z","steps":["trace[414334200] 'process raft request' (duration: 188.555709ms)","trace[414334200] 'compare' (duration: 26.221146ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:13.480796Z","caller":"traceutil/trace.go:171","msg":"trace[1060246244] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"214.178213ms","start":"2026-05-27T19:44:13.266592Z","end":"2026-05-27T19:44:13.48077Z","steps":["trace[1060246244] 'process raft request' (duration: 213.980889ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:13.483561Z","caller":"traceutil/trace.go:171","msg":"trace[246718911] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"122.716542ms","start":"2026-05-27T19:44:13.360825Z","end":"2026-05-27T19:44:13.483541Z","steps":["trace[246718911] 'process raft request' (duration: 120.52993ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:15.883682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.600955ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:15.883821Z","caller":"traceutil/trace.go:171","msg":"trace[801230754] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"323.624741ms","start":"2026-05-27T19:44:15.56015Z","end":"2026-05-27T19:44:15.883774Z","steps":["trace[801230754] 'process raft request' (duration: 207.858102ms)","trace[801230754] 'compare' (duration: 115.482833ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:15.883973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:15.560119Z","time spent":"323.757865ms","remote":"127.0.0.1:50156","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":397,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:44:16.070653Z","caller":"traceutil/trace.go:171","msg":"trace[1608185348] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"183.032874ms","start":"2026-05-27T19:44:15.887594Z","end":"2026-05-27T19:44:16.070626Z","steps":["trace[1608185348] 'process raft request' (duration: 103.593085ms)","trace[1608185348] 'compare' (duration: 79.314367ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:20.144187Z","caller":"traceutil/trace.go:171","msg":"trace[1749164191] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:663; }","duration":"143.436531ms","start":"2026-05-27T19:44:20.00072Z","end":"2026-05-27T19:44:20.144157Z","steps":["trace[1749164191] 'read index received' (duration: 142.682917ms)","trace[1749164191] 'applied index is now lower than readState.Index' (duration: 752.234µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:20.144313Z","caller":"traceutil/trace.go:171","msg":"trace[494851135] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"375.000348ms","start":"2026-05-27T19:44:19.76927Z","end":"2026-05-27T19:44:20.14427Z","steps":["trace[494851135] 'process raft request' (duration: 374.381357ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:20.144535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:19.769244Z","time spent":"375.180862ms","remote":"127.0.0.1:50150","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:20.144651Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.911571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-7db7g\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-05-27T19:44:20.144763Z","caller":"traceutil/trace.go:171","msg":"trace[1500406507] range","detail":"{range_begin:/registry/pods/kube-system/cilium-7db7g; range_end:; response_count:1; response_revision:644; }","duration":"144.059003ms","start":"2026-05-27T19:44:20.000684Z","end":"2026-05-27T19:44:20.144743Z","steps":["trace[1500406507] 'agreement among raft nodes before linearized reading' (duration: 143.683085ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:20.276336Z","caller":"traceutil/trace.go:171","msg":"trace[1742514129] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"105.435001ms","start":"2026-05-27T19:44:20.170871Z","end":"2026-05-27T19:44:20.276306Z","steps":["trace[1742514129] 'process raft request' (duration: 94.49005ms)","trace[1742514129] 'compare' (duration: 10.684326ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:20.74636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.68325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:20.747233Z","caller":"traceutil/trace.go:171","msg":"trace[28564574] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"545.847409ms","start":"2026-05-27T19:44:20.201356Z","end":"2026-05-27T19:44:20.747203Z","steps":["trace[28564574] 'process raft request' (duration: 366.271781ms)","trace[28564574] 'compare' (duration: 178.457086ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:20.747451Z","caller":"traceutil/trace.go:171","msg":"trace[338712420] linearizableReadLoop","detail":"{readStateIndex:666; appliedIndex:665; }","duration":"242.925726ms","start":"2026-05-27T19:44:20.504509Z","end":"2026-05-27T19:44:20.747435Z","steps":["trace[338712420] 'read index received' (duration: 63.071264ms)","trace[338712420] 'applied index is now lower than readState.Index' (duration: 179.852662ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:20.747716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:20.201333Z","time spent":"545.971071ms","remote":"127.0.0.1:50292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:44:20.788688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.323262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:20.788775Z","caller":"traceutil/trace.go:171","msg":"trace[1251745054] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:646; }","duration":"248.452364ms","start":"2026-05-27T19:44:20.540298Z","end":"2026-05-27T19:44:20.788751Z","steps":["trace[1251745054] 'agreement among raft nodes before linearized reading' (duration: 248.318191ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:20.788926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.220486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-27T19:44:20.788696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.183362ms","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-05-27T19:44:20.789008Z","caller":"traceutil/trace.go:171","msg":"trace[643596233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:646; }","duration":"265.350479ms","start":"2026-05-27T19:44:20.52364Z","end":"2026-05-27T19:44:20.788991Z","steps":["trace[643596233] 'agreement among raft nodes before linearized reading' (duration: 265.071874ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:20.789111Z","caller":"traceutil/trace.go:171","msg":"trace[281092084] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:646; }","duration":"284.557289ms","start":"2026-05-27T19:44:20.504452Z","end":"2026-05-27T19:44:20.789009Z","steps":["trace[281092084] 'agreement among raft nodes before linearized reading' (duration: 243.09442ms)","trace[281092084] 'range keys from in-memory index tree' (duration: 40.99132ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:20.789153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.580726ms","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-05-27T19:44:20.789418Z","caller":"traceutil/trace.go:171","msg":"trace[1823848095] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:646; }","duration":"261.916073ms","start":"2026-05-27T19:44:20.527482Z","end":"2026-05-27T19:44:20.789399Z","steps":["trace[1823848095] 'agreement among raft nodes before linearized reading' (duration: 261.19805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:21.345589Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776132189,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:44:22.097805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"541.72607ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:44:22.09798Z","caller":"traceutil/trace.go:171","msg":"trace[92199929] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"1.252715918s","start":"2026-05-27T19:44:20.845248Z","end":"2026-05-27T19:44:22.097964Z","steps":["trace[92199929] 'read index received' (duration: 710.649372ms)","trace[92199929] 'applied index is now lower than readState.Index' (duration: 542.065676ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:22.098025Z","caller":"traceutil/trace.go:171","msg":"trace[1957383178] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"1.305042895s","start":"2026-05-27T19:44:20.792946Z","end":"2026-05-27T19:44:22.097988Z","steps":["trace[1957383178] 'process raft request' (duration: 763.03869ms)","trace[1957383178] 'compare' (duration: 541.618777ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:22.098082Z","caller":"traceutil/trace.go:171","msg":"trace[191838266] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"1.303834682s","start":"2026-05-27T19:44:20.794234Z","end":"2026-05-27T19:44:22.098068Z","steps":["trace[191838266] 'process raft request' (duration: 1.303673589s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:22.098143Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:20.792932Z","time spent":"1.305149737s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:22.098169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:20.794226Z","time spent":"1.303895833s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:22.098281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.253024513s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:22.098359Z","caller":"traceutil/trace.go:171","msg":"trace[1780393330] range","detail":"{range_begin:/registry/limitranges/envoy-gateway-system/; range_end:/registry/limitranges/envoy-gateway-system0; response_count:0; response_revision:649; }","duration":"1.253143246s","start":"2026-05-27T19:44:20.845195Z","end":"2026-05-27T19:44:22.098339Z","steps":["trace[1780393330] 'agreement among raft nodes before linearized reading' (duration: 1.253021083s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:22.098384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.110656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:44:22.098386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.296044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:44:22.098411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:20.845175Z","time spent":"1.253223627s","remote":"127.0.0.1:50194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/envoy-gateway-system/\" range_end:\"/registry/limitranges/envoy-gateway-system0\" "} {"level":"info","ts":"2026-05-27T19:44:22.098427Z","caller":"traceutil/trace.go:171","msg":"trace[1265425547] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:649; }","duration":"115.150817ms","start":"2026-05-27T19:44:21.983262Z","end":"2026-05-27T19:44:22.098413Z","steps":["trace[1265425547] 'agreement among raft nodes before linearized reading' (duration: 115.088256ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:22.098432Z","caller":"traceutil/trace.go:171","msg":"trace[1521741290] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:649; }","duration":"577.403916ms","start":"2026-05-27T19:44:21.521015Z","end":"2026-05-27T19:44:22.098419Z","steps":["trace[1521741290] 'agreement among raft nodes before linearized reading' (duration: 577.205432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:22.098584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:21.520996Z","time spent":"577.566549ms","remote":"127.0.0.1:50314","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-05-27T19:44:24.40298Z","caller":"traceutil/trace.go:171","msg":"trace[763076313] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"227.491499ms","start":"2026-05-27T19:44:24.17547Z","end":"2026-05-27T19:44:24.402961Z","steps":["trace[763076313] 'process raft request' (duration: 221.780839ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:24.409363Z","caller":"traceutil/trace.go:171","msg":"trace[2094349530] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"212.234497ms","start":"2026-05-27T19:44:24.197111Z","end":"2026-05-27T19:44:24.409345Z","steps":["trace[2094349530] 'process raft request' (duration: 212.126044ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:26.535837Z","caller":"traceutil/trace.go:171","msg":"trace[1410644815] linearizableReadLoop","detail":"{readStateIndex:739; appliedIndex:738; }","duration":"128.768409ms","start":"2026-05-27T19:44:26.407045Z","end":"2026-05-27T19:44:26.535813Z","steps":["trace[1410644815] 'read index received' (duration: 128.624666ms)","trace[1410644815] 'applied index is now lower than readState.Index' (duration: 142.743µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:26.536059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.001743ms","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-05-27T19:44:26.536049Z","caller":"traceutil/trace.go:171","msg":"trace[2020932942] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"138.21135ms","start":"2026-05-27T19:44:26.397815Z","end":"2026-05-27T19:44:26.536026Z","steps":["trace[2020932942] 'process raft request' (duration: 137.872894ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:26.536096Z","caller":"traceutil/trace.go:171","msg":"trace[645585948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:717; }","duration":"129.094416ms","start":"2026-05-27T19:44:26.406991Z","end":"2026-05-27T19:44:26.536086Z","steps":["trace[645585948] 'agreement among raft nodes before linearized reading' (duration: 128.914962ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:27.353328Z","caller":"traceutil/trace.go:171","msg":"trace[1122952904] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"129.069485ms","start":"2026-05-27T19:44:27.224224Z","end":"2026-05-27T19:44:27.353293Z","steps":["trace[1122952904] 'process raft request' (duration: 128.894862ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:28.271485Z","caller":"traceutil/trace.go:171","msg":"trace[1838080461] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:756; }","duration":"113.916753ms","start":"2026-05-27T19:44:28.157543Z","end":"2026-05-27T19:44:28.27146Z","steps":["trace[1838080461] 'read index received' (duration: 103.167615ms)","trace[1838080461] 'applied index is now lower than readState.Index' (duration: 10.747988ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:28.271632Z","caller":"traceutil/trace.go:171","msg":"trace[765323593] transaction","detail":"{read_only:false; response_revision:735; number_of_response:1; }","duration":"173.615412ms","start":"2026-05-27T19:44:28.097982Z","end":"2026-05-27T19:44:28.271598Z","steps":["trace[765323593] 'process raft request' (duration: 162.646561ms)","trace[765323593] 'compare' (duration: 10.629405ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:44:28.271775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.219418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-27T19:44:28.271827Z","caller":"traceutil/trace.go:171","msg":"trace[214399493] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:735; }","duration":"114.3187ms","start":"2026-05-27T19:44:28.157494Z","end":"2026-05-27T19:44:28.271813Z","steps":["trace[214399493] 'agreement among raft nodes before linearized reading' (duration: 114.114927ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:28.271764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.205548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-27T19:44:28.271932Z","caller":"traceutil/trace.go:171","msg":"trace[295286579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:735; }","duration":"114.412962ms","start":"2026-05-27T19:44:28.157503Z","end":"2026-05-27T19:44:28.271916Z","steps":["trace[295286579] 'agreement among raft nodes before linearized reading' (duration: 114.089636ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:35.639131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.398873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:35.639212Z","caller":"traceutil/trace.go:171","msg":"trace[1527396419] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"100.535786ms","start":"2026-05-27T19:44:35.538658Z","end":"2026-05-27T19:44:35.639194Z","steps":["trace[1527396419] 'range keys from in-memory index tree' (duration: 100.304252ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:38.824439Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776132506,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:44:39.304906Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.198057093s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:44:39.305789Z","caller":"traceutil/trace.go:171","msg":"trace[1459345298] transaction","detail":"{read_only:false; response_revision:810; number_of_response:1; }","duration":"1.199148055s","start":"2026-05-27T19:44:38.106607Z","end":"2026-05-27T19:44:39.305755Z","steps":["trace[1459345298] 'process raft request' (duration: 1.198915261s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.306057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.106559Z","time spent":"1.199338629s","remote":"127.0.0.1:50228","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4027,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:44:39.365337Z","caller":"traceutil/trace.go:171","msg":"trace[1411944676] linearizableReadLoop","detail":"{readStateIndex:838; appliedIndex:835; }","duration":"1.041400292s","start":"2026-05-27T19:44:38.323903Z","end":"2026-05-27T19:44:39.365303Z","steps":["trace[1411944676] 'read index received' (duration: 981.376682ms)","trace[1411944676] 'applied index is now lower than readState.Index' (duration: 60.02182ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:44:39.365367Z","caller":"traceutil/trace.go:171","msg":"trace[1431724457] transaction","detail":"{read_only:false; response_revision:811; number_of_response:1; }","duration":"1.003654116s","start":"2026-05-27T19:44:38.361675Z","end":"2026-05-27T19:44:39.365329Z","steps":["trace[1431724457] 'process raft request' (duration: 1.003318059s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:39.365435Z","caller":"traceutil/trace.go:171","msg":"trace[1860783918] transaction","detail":"{read_only:false; response_revision:812; number_of_response:1; }","duration":"807.155633ms","start":"2026-05-27T19:44:38.558234Z","end":"2026-05-27T19:44:39.365389Z","steps":["trace[1860783918] 'process raft request' (duration: 807.00035ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.365533Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.361638Z","time spent":"1.003803928s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:39.365637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.558209Z","time spent":"807.328758ms","remote":"127.0.0.1:50224","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:44:39.365682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.038640851s","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-05-27T19:44:39.365729Z","caller":"traceutil/trace.go:171","msg":"trace[1365146375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.038737692s","start":"2026-05-27T19:44:38.326977Z","end":"2026-05-27T19:44:39.365714Z","steps":["trace[1365146375] 'agreement among raft nodes before linearized reading' (duration: 1.038502438s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.365748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"736.681957ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:44:39.365759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.041708719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-27T19:44:39.365783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.326967Z","time spent":"1.038803263s","remote":"127.0.0.1:50314","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-05-27T19:44:39.365819Z","caller":"traceutil/trace.go:171","msg":"trace[2076775073] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:812; }","duration":"736.80504ms","start":"2026-05-27T19:44:38.628995Z","end":"2026-05-27T19:44:39.365801Z","steps":["trace[2076775073] 'agreement among raft nodes before linearized reading' (duration: 736.608316ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:39.365835Z","caller":"traceutil/trace.go:171","msg":"trace[509152821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:812; }","duration":"1.041943154s","start":"2026-05-27T19:44:38.323868Z","end":"2026-05-27T19:44:39.365811Z","steps":["trace[509152821] 'agreement among raft nodes before linearized reading' (duration: 1.041583986s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.365865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.628965Z","time spent":"736.892852ms","remote":"127.0.0.1:50314","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-05-27T19:44:39.365876Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.323849Z","time spent":"1.042015095s","remote":"127.0.0.1:50314","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-05-27T19:44:39.366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"825.291653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:44:39.366027Z","caller":"traceutil/trace.go:171","msg":"trace[786339361] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:812; }","duration":"825.372305ms","start":"2026-05-27T19:44:38.540645Z","end":"2026-05-27T19:44:39.366018Z","steps":["trace[786339361] 'agreement among raft nodes before linearized reading' (duration: 825.326634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.366051Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.540612Z","time spent":"825.434286ms","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:44:39.366091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"889.327146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:44:39.366176Z","caller":"traceutil/trace.go:171","msg":"trace[924532064] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:812; }","duration":"889.463827ms","start":"2026-05-27T19:44:38.476691Z","end":"2026-05-27T19:44:39.366155Z","steps":["trace[924532064] 'agreement among raft nodes before linearized reading' (duration: 889.265084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.366099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.946569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-27T19:44:39.366218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.47667Z","time spent":"889.53921ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":94,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:44:39.366127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.161102ms","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-05-27T19:44:39.366237Z","caller":"traceutil/trace.go:171","msg":"trace[207518767] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:812; }","duration":"929.115722ms","start":"2026-05-27T19:44:38.437096Z","end":"2026-05-27T19:44:39.366212Z","steps":["trace[207518767] 'agreement among raft nodes before linearized reading' (duration: 928.920388ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:44:39.366267Z","caller":"traceutil/trace.go:171","msg":"trace[1467080944] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:812; }","duration":"448.324155ms","start":"2026-05-27T19:44:38.917931Z","end":"2026-05-27T19:44:39.366255Z","steps":["trace[1467080944] 'agreement among raft nodes before linearized reading' (duration: 448.136532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:44:39.366291Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.437072Z","time spent":"929.205244ms","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":52,"response size":30,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:44:39.3663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:44:38.917912Z","time spent":"448.380346ms","remote":"127.0.0.1:50314","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-05-27T19:45:14.707271Z","caller":"traceutil/trace.go:171","msg":"trace[138541715] transaction","detail":"{read_only:false; response_revision:1031; number_of_response:1; }","duration":"272.595138ms","start":"2026-05-27T19:45:14.434648Z","end":"2026-05-27T19:45:14.707244Z","steps":["trace[138541715] 'process raft request' (duration: 272.423905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.19662Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776133249,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-27T19:45:40.477224Z","caller":"traceutil/trace.go:171","msg":"trace[1519946053] linearizableReadLoop","detail":"{readStateIndex:1151; appliedIndex:1150; }","duration":"781.141469ms","start":"2026-05-27T19:45:39.696058Z","end":"2026-05-27T19:45:40.4772Z","steps":["trace[1519946053] 'read index received' (duration: 780.893163ms)","trace[1519946053] 'applied index is now lower than readState.Index' (duration: 247.236µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:40.4773Z","caller":"traceutil/trace.go:171","msg":"trace[1614381996] transaction","detail":"{read_only:false; response_revision:1112; number_of_response:1; }","duration":"793.765839ms","start":"2026-05-27T19:45:39.683489Z","end":"2026-05-27T19:45:40.477254Z","steps":["trace[1614381996] 'process raft request' (duration: 793.522273ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.477483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.307463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-05-27T19:45:40.477503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:39.68347Z","time spent":"793.927673ms","remote":"127.0.0.1:50314","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-05-27T19:45:40.477514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"781.446525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-05-27T19:45:40.477575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.671815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:45:40.47758Z","caller":"traceutil/trace.go:171","msg":"trace[121341959] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1112; }","duration":"781.550237ms","start":"2026-05-27T19:45:39.696011Z","end":"2026-05-27T19:45:40.477562Z","steps":["trace[121341959] 'agreement among raft nodes before linearized reading' (duration: 781.276372ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.477489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.411866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:40.477615Z","caller":"traceutil/trace.go:171","msg":"trace[1693950045] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:1112; }","duration":"118.750157ms","start":"2026-05-27T19:45:40.358852Z","end":"2026-05-27T19:45:40.477602Z","steps":["trace[1693950045] 'agreement among raft nodes before linearized reading' (duration: 118.697096ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:40.477528Z","caller":"traceutil/trace.go:171","msg":"trace[900018171] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1112; }","duration":"781.411884ms","start":"2026-05-27T19:45:39.696104Z","end":"2026-05-27T19:45:40.477516Z","steps":["trace[900018171] 'agreement among raft nodes before linearized reading' (duration: 781.241581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.477658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:39.696098Z","time spent":"781.552598ms","remote":"127.0.0.1:50314","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-05-27T19:45:40.47762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:39.695994Z","time spent":"781.618228ms","remote":"127.0.0.1:50314","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-05-27T19:45:40.477606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.494725ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-05-27T19:45:40.477717Z","caller":"traceutil/trace.go:171","msg":"trace[609588759] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1112; }","duration":"145.66211ms","start":"2026-05-27T19:45:40.331996Z","end":"2026-05-27T19:45:40.477658Z","steps":["trace[609588759] 'agreement among raft nodes before linearized reading' (duration: 145.416625ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.477793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"778.839853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:45:40.477827Z","caller":"traceutil/trace.go:171","msg":"trace[1054244335] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1112; }","duration":"221.711428ms","start":"2026-05-27T19:45:40.256075Z","end":"2026-05-27T19:45:40.477786Z","steps":["trace[1054244335] 'agreement among raft nodes before linearized reading' (duration: 221.476594ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:40.477871Z","caller":"traceutil/trace.go:171","msg":"trace[1153174509] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1112; }","duration":"778.955485ms","start":"2026-05-27T19:45:39.698895Z","end":"2026-05-27T19:45:40.477851Z","steps":["trace[1153174509] 'agreement among raft nodes before linearized reading' (duration: 778.68831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:40.477906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:39.698887Z","time spent":"779.010976ms","remote":"127.0.0.1:50314","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-05-27T19:45:41.132075Z","caller":"traceutil/trace.go:171","msg":"trace[2049196798] transaction","detail":"{read_only:false; response_revision:1113; number_of_response:1; }","duration":"649.225022ms","start":"2026-05-27T19:45:40.48279Z","end":"2026-05-27T19:45:41.132015Z","steps":["trace[2049196798] 'process raft request' (duration: 641.239034ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:41.132204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.48277Z","time spent":"649.366285ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:41.342449Z","caller":"traceutil/trace.go:171","msg":"trace[452931096] transaction","detail":"{read_only:false; response_revision:1115; number_of_response:1; }","duration":"859.22683ms","start":"2026-05-27T19:45:40.483201Z","end":"2026-05-27T19:45:41.342428Z","steps":["trace[452931096] 'process raft request' (duration: 859.169048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:41.342579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.483176Z","time spent":"859.332022ms","remote":"127.0.0.1:50314","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-05-27T19:45:41.34249Z","caller":"traceutil/trace.go:171","msg":"trace[827189539] transaction","detail":"{read_only:false; response_revision:1114; number_of_response:1; }","duration":"859.525666ms","start":"2026-05-27T19:45:40.482926Z","end":"2026-05-27T19:45:41.342452Z","steps":["trace[827189539] 'process raft request' (duration: 763.862327ms)","trace[827189539] 'compare' (duration: 95.450884ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:41.342814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.4829Z","time spent":"859.791751ms","remote":"127.0.0.1:50314","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-05-27T19:45:41.342836Z","caller":"traceutil/trace.go:171","msg":"trace[565183301] transaction","detail":"{read_only:false; response_revision:1116; number_of_response:1; }","duration":"859.174439ms","start":"2026-05-27T19:45:40.483644Z","end":"2026-05-27T19:45:41.342818Z","steps":["trace[565183301] 'process raft request' (duration: 858.7555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:41.343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.483629Z","time spent":"859.323692ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:45:41.51073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.974503ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-05-27T19:45:41.510958Z","caller":"traceutil/trace.go:171","msg":"trace[143458759] transaction","detail":"{read_only:false; response_revision:1117; number_of_response:1; }","duration":"677.506013ms","start":"2026-05-27T19:45:40.833434Z","end":"2026-05-27T19:45:41.51094Z","steps":["trace[143458759] 'process raft request' (duration: 677.389141ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:41.511077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.833408Z","time spent":"677.601806ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:41.585986Z","caller":"traceutil/trace.go:171","msg":"trace[370741531] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"698.52028ms","start":"2026-05-27T19:45:40.887439Z","end":"2026-05-27T19:45:41.58596Z","steps":["trace[370741531] 'process raft request' (duration: 623.485571ms)","trace[370741531] 'compare' (duration: 74.676202ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:41.586125Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:40.887411Z","time spent":"698.638203ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:45:42.726264Z","caller":"traceutil/trace.go:171","msg":"trace[695032148] linearizableReadLoop","detail":"{readStateIndex:1159; appliedIndex:1158; }","duration":"185.866878ms","start":"2026-05-27T19:45:42.540375Z","end":"2026-05-27T19:45:42.726242Z","steps":["trace[695032148] 'read index received' (duration: 185.605283ms)","trace[695032148] 'applied index is now lower than readState.Index' (duration: 260.605µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:45:42.726332Z","caller":"traceutil/trace.go:171","msg":"trace[710785675] transaction","detail":"{read_only:false; response_revision:1119; number_of_response:1; }","duration":"239.623854ms","start":"2026-05-27T19:45:42.486665Z","end":"2026-05-27T19:45:42.726289Z","steps":["trace[710785675] 'process raft request' (duration: 239.39229ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:42.72642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.021571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:42.726498Z","caller":"traceutil/trace.go:171","msg":"trace[1684253580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1119; }","duration":"186.155974ms","start":"2026-05-27T19:45:42.540324Z","end":"2026-05-27T19:45:42.72648Z","steps":["trace[1684253580] 'agreement among raft nodes before linearized reading' (duration: 186.010541ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:43.293396Z","caller":"traceutil/trace.go:171","msg":"trace[1909039155] transaction","detail":"{read_only:false; response_revision:1120; number_of_response:1; }","duration":"152.395914ms","start":"2026-05-27T19:45:43.140972Z","end":"2026-05-27T19:45:43.293368Z","steps":["trace[1909039155] 'process raft request' (duration: 152.20942ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:43.694877Z","caller":"traceutil/trace.go:171","msg":"trace[313739211] linearizableReadLoop","detail":"{readStateIndex:1164; appliedIndex:1163; }","duration":"154.234031ms","start":"2026-05-27T19:45:43.540614Z","end":"2026-05-27T19:45:43.694848Z","steps":["trace[313739211] 'read index received' (duration: 153.964745ms)","trace[313739211] 'applied index is now lower than readState.Index' (duration: 268.116µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:45:43.69506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.368474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:45:43.695108Z","caller":"traceutil/trace.go:171","msg":"trace[1661811170] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1124; }","duration":"154.524147ms","start":"2026-05-27T19:45:43.540567Z","end":"2026-05-27T19:45:43.695091Z","steps":["trace[1661811170] 'agreement among raft nodes before linearized reading' (duration: 154.387774ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:45:43.695047Z","caller":"traceutil/trace.go:171","msg":"trace[102475940] transaction","detail":"{read_only:false; response_revision:1124; number_of_response:1; }","duration":"177.728417ms","start":"2026-05-27T19:45:43.517239Z","end":"2026-05-27T19:45:43.694967Z","steps":["trace[102475940] 'process raft request' (duration: 177.450321ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:45:59.944537Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776133509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:46:00.444926Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776133509,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:46:00.687175Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.311939152s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:46:00.687616Z","caller":"traceutil/trace.go:171","msg":"trace[1002090544] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"1.312466252s","start":"2026-05-27T19:45:59.375115Z","end":"2026-05-27T19:46:00.687581Z","steps":["trace[1002090544] 'process raft request' (duration: 1.312280499s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.687818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.375092Z","time spent":"1.312625575s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:00.739651Z","caller":"traceutil/trace.go:171","msg":"trace[1747689592] linearizableReadLoop","detail":"{readStateIndex:1271; appliedIndex:1269; }","duration":"1.29569731s","start":"2026-05-27T19:45:59.443921Z","end":"2026-05-27T19:46:00.739618Z","steps":["trace[1747689592] 'read index received' (duration: 1.243604356s)","trace[1747689592] 'applied index is now lower than readState.Index' (duration: 52.091904ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:00.739797Z","caller":"traceutil/trace.go:171","msg":"trace[811621250] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"972.15606ms","start":"2026-05-27T19:45:59.767627Z","end":"2026-05-27T19:46:00.739783Z","steps":["trace[811621250] 'process raft request' (duration: 971.325984ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.739917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.767587Z","time spent":"972.230912ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:00.740026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296083958s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:46:00.740101Z","caller":"traceutil/trace.go:171","msg":"trace[406914949] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1226; }","duration":"1.296201949s","start":"2026-05-27T19:45:59.443878Z","end":"2026-05-27T19:46:00.74008Z","steps":["trace[406914949] 'agreement among raft nodes before linearized reading' (duration: 1.295955874s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.740165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.443855Z","time spent":"1.296300932s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:00.740229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296192619s","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-05-27T19:46:00.740258Z","caller":"traceutil/trace.go:171","msg":"trace[862426986] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1226; }","duration":"1.296264621s","start":"2026-05-27T19:45:59.443985Z","end":"2026-05-27T19:46:00.74025Z","steps":["trace[862426986] 'agreement among raft nodes before linearized reading' (duration: 1.296137008s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.74026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.296333073s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-27T19:46:00.740292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.443952Z","time spent":"1.296336542s","remote":"127.0.0.1:50314","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-05-27T19:46:00.7403Z","caller":"traceutil/trace.go:171","msg":"trace[1340944397] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1226; }","duration":"1.296409913s","start":"2026-05-27T19:45:59.443878Z","end":"2026-05-27T19:46:00.740288Z","steps":["trace[1340944397] 'agreement among raft nodes before linearized reading' (duration: 1.296292271s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.740307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.472242ms","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":"warn","ts":"2026-05-27T19:46:00.740348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.443855Z","time spent":"1.296488416s","remote":"127.0.0.1:50314","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-05-27T19:46:00.74036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.202571982s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:00.740371Z","caller":"traceutil/trace.go:171","msg":"trace[1998150943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1226; }","duration":"1.202630123s","start":"2026-05-27T19:45:59.537737Z","end":"2026-05-27T19:46:00.740367Z","steps":["trace[1998150943] 'agreement among raft nodes before linearized reading' (duration: 1.202604442s)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:00.740371Z","caller":"traceutil/trace.go:171","msg":"trace[1145219287] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:1226; }","duration":"361.592705ms","start":"2026-05-27T19:46:00.37876Z","end":"2026-05-27T19:46:00.740353Z","steps":["trace[1145219287] 'agreement among raft nodes before linearized reading' (duration: 361.485623ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.740434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.25839912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:00.740448Z","caller":"traceutil/trace.go:171","msg":"trace[136433203] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1226; }","duration":"1.258460461s","start":"2026-05-27T19:45:59.481983Z","end":"2026-05-27T19:46:00.740444Z","steps":["trace[136433203] 'agreement among raft nodes before linearized reading' (duration: 1.258436931s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:00.740444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:00.378732Z","time spent":"361.700986ms","remote":"127.0.0.1:33808","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-05-27T19:46:00.740461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.481958Z","time spent":"1.258498982s","remote":"127.0.0.1:50194","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:00.74041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:45:59.537706Z","time spent":"1.202688204s","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:46:14.982715Z","caller":"traceutil/trace.go:171","msg":"trace[1306181309] transaction","detail":"{read_only:false; response_revision:1275; number_of_response:1; }","duration":"112.265048ms","start":"2026-05-27T19:46:14.870402Z","end":"2026-05-27T19:46:14.982666Z","steps":["trace[1306181309] 'process raft request' (duration: 111.992092ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:18.321723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.911586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:18.321815Z","caller":"traceutil/trace.go:171","msg":"trace[1264516617] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1300; }","duration":"781.031968ms","start":"2026-05-27T19:46:17.540762Z","end":"2026-05-27T19:46:18.321794Z","steps":["trace[1264516617] 'range keys from in-memory index tree' (duration: 780.778193ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:18.321849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:17.540678Z","time spent":"781.16478ms","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-27T19:46:18.32205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.807336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-m9b26\" ","response":"range_response_count:1 size:11057"} {"level":"info","ts":"2026-05-27T19:46:18.322199Z","caller":"traceutil/trace.go:171","msg":"trace[1674648221] range","detail":"{range_begin:/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-m9b26; range_end:; response_count:1; response_revision:1300; }","duration":"829.03447ms","start":"2026-05-27T19:46:17.493145Z","end":"2026-05-27T19:46:18.32218Z","steps":["trace[1674648221] 'range keys from in-memory index tree' (duration: 828.606002ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:18.322248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:17.493118Z","time spent":"829.120851ms","remote":"127.0.0.1:50228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":1,"response size":11080,"request content":"key:\"/registry/pods/kube-system/ceph-csi-rbd-nodeplugin-m9b26\" "} {"level":"info","ts":"2026-05-27T19:46:18.595951Z","caller":"traceutil/trace.go:171","msg":"trace[602944531] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"163.660978ms","start":"2026-05-27T19:46:18.432252Z","end":"2026-05-27T19:46:18.595913Z","steps":["trace[602944531] 'process raft request' (duration: 107.163596ms)","trace[602944531] 'compare' (duration: 56.358948ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:18.658658Z","caller":"traceutil/trace.go:171","msg":"trace[527074443] transaction","detail":"{read_only:false; response_revision:1303; number_of_response:1; }","duration":"225.365772ms","start":"2026-05-27T19:46:18.433244Z","end":"2026-05-27T19:46:18.658609Z","steps":["trace[527074443] 'process raft request' (duration: 225.109557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:18.659016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.759998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:18.659072Z","caller":"traceutil/trace.go:171","msg":"trace[20761546] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1304; }","duration":"116.90489ms","start":"2026-05-27T19:46:18.542157Z","end":"2026-05-27T19:46:18.659062Z","steps":["trace[20761546] 'agreement among raft nodes before linearized reading' (duration: 116.742597ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:18.659086Z","caller":"traceutil/trace.go:171","msg":"trace[1709173795] transaction","detail":"{read_only:false; response_revision:1304; number_of_response:1; }","duration":"220.955325ms","start":"2026-05-27T19:46:18.438087Z","end":"2026-05-27T19:46:18.659042Z","steps":["trace[1709173795] 'process raft request' (duration: 220.444114ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:18.658807Z","caller":"traceutil/trace.go:171","msg":"trace[183910344] linearizableReadLoop","detail":"{readStateIndex:1353; appliedIndex:1351; }","duration":"116.543333ms","start":"2026-05-27T19:46:18.542227Z","end":"2026-05-27T19:46:18.65877Z","steps":["trace[183910344] 'read index received' (duration: 116.001062ms)","trace[183910344] 'applied index is now lower than readState.Index' (duration: 539.971µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:19.058935Z","caller":"traceutil/trace.go:171","msg":"trace[1606728645] transaction","detail":"{read_only:false; response_revision:1306; number_of_response:1; }","duration":"226.446713ms","start":"2026-05-27T19:46:18.832447Z","end":"2026-05-27T19:46:19.058893Z","steps":["trace[1606728645] 'process raft request' (duration: 147.739011ms)","trace[1606728645] 'compare' (duration: 78.422936ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:19.059148Z","caller":"traceutil/trace.go:171","msg":"trace[1399359671] linearizableReadLoop","detail":"{readStateIndex:1356; appliedIndex:1354; }","duration":"191.628932ms","start":"2026-05-27T19:46:18.867489Z","end":"2026-05-27T19:46:19.059118Z","steps":["trace[1399359671] 'read index received' (duration: 7.239874ms)","trace[1399359671] 'applied index is now lower than readState.Index' (duration: 184.387508ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:19.05936Z","caller":"traceutil/trace.go:171","msg":"trace[148920551] transaction","detail":"{read_only:false; response_revision:1307; number_of_response:1; }","duration":"208.899076ms","start":"2026-05-27T19:46:18.850444Z","end":"2026-05-27T19:46:19.059343Z","steps":["trace[148920551] 'process raft request' (duration: 208.584719ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:19.059721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.734156ms","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-05-27T19:46:19.059777Z","caller":"traceutil/trace.go:171","msg":"trace[674837626] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1307; }","duration":"181.862518ms","start":"2026-05-27T19:46:18.877901Z","end":"2026-05-27T19:46:19.059763Z","steps":["trace[674837626] 'agreement among raft nodes before linearized reading' (duration: 181.712085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:19.059921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.403667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:46:19.059965Z","caller":"traceutil/trace.go:171","msg":"trace[1796826139] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1307; }","duration":"192.51811ms","start":"2026-05-27T19:46:18.867434Z","end":"2026-05-27T19:46:19.059953Z","steps":["trace[1796826139] 'agreement among raft nodes before linearized reading' (duration: 191.881288ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:19.462475Z","caller":"traceutil/trace.go:171","msg":"trace[1634167123] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"396.61545ms","start":"2026-05-27T19:46:19.065826Z","end":"2026-05-27T19:46:19.462441Z","steps":["trace[1634167123] 'process raft request' (duration: 338.085979ms)","trace[1634167123] 'compare' (duration: 57.9481ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:19.46282Z","caller":"traceutil/trace.go:171","msg":"trace[246406707] transaction","detail":"{read_only:false; response_revision:1309; number_of_response:1; }","duration":"396.759573ms","start":"2026-05-27T19:46:19.066028Z","end":"2026-05-27T19:46:19.462788Z","steps":["trace[246406707] 'process raft request' (duration: 396.349865ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:19.462984Z","caller":"traceutil/trace.go:171","msg":"trace[1936062627] transaction","detail":"{read_only:false; response_revision:1310; number_of_response:1; }","duration":"396.721762ms","start":"2026-05-27T19:46:19.066248Z","end":"2026-05-27T19:46:19.46297Z","steps":["trace[1936062627] 'process raft request' (duration: 396.450437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:19.462994Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.065997Z","time spent":"396.894256ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:19.463078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.066241Z","time spent":"396.780154ms","remote":"127.0.0.1:50314","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-05-27T19:46:19.46315Z","caller":"traceutil/trace.go:171","msg":"trace[594781651] transaction","detail":"{read_only:false; response_revision:1311; number_of_response:1; }","duration":"395.776103ms","start":"2026-05-27T19:46:19.067363Z","end":"2026-05-27T19:46:19.463139Z","steps":["trace[594781651] 'process raft request' (duration: 395.556049ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:19.463201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.067333Z","time spent":"395.838455ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:19.463517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.065796Z","time spent":"396.771663ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:20.444161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.559905ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:20.444281Z","caller":"traceutil/trace.go:171","msg":"trace[510208010] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:1311; }","duration":"165.76253ms","start":"2026-05-27T19:46:20.278495Z","end":"2026-05-27T19:46:20.444257Z","steps":["trace[510208010] 'count revisions from in-memory index tree' (duration: 165.460004ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.444152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.352256ms","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-05-27T19:46:20.444958Z","caller":"traceutil/trace.go:171","msg":"trace[1768480985] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1311; }","duration":"455.237633ms","start":"2026-05-27T19:46:19.989706Z","end":"2026-05-27T19:46:20.444944Z","steps":["trace[1768480985] 'count revisions from in-memory index tree' (duration: 454.225953ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:20.444997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:19.989678Z","time spent":"455.305754ms","remote":"127.0.0.1:50248","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":45,"response size":30,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:21.914929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.142158ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-27T19:46:21.915377Z","caller":"traceutil/trace.go:171","msg":"trace[483107007] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1364; }","duration":"447.870616ms","start":"2026-05-27T19:46:21.467483Z","end":"2026-05-27T19:46:21.915354Z","steps":["trace[483107007] 'read index received' (duration: 319.178243ms)","trace[483107007] 'applied index is now lower than readState.Index' (duration: 128.690943ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:46:21.915418Z","caller":"traceutil/trace.go:171","msg":"trace[1428211854] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"612.35835ms","start":"2026-05-27T19:46:21.303023Z","end":"2026-05-27T19:46:21.915382Z","steps":["trace[1428211854] 'process raft request' (duration: 612.144526ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.915579Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.302985Z","time spent":"612.515543ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:46:21.915746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.244894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-05-27T19:46:21.915763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.185323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:46:21.915854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.763645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:46:21.915982Z","caller":"traceutil/trace.go:171","msg":"trace[1971855869] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1316; }","duration":"447.874368ms","start":"2026-05-27T19:46:21.468058Z","end":"2026-05-27T19:46:21.915932Z","steps":["trace[1971855869] 'agreement among raft nodes before linearized reading' (duration: 447.517561ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.916087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.468048Z","time spent":"448.01912ms","remote":"127.0.0.1:50314","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-05-27T19:46:21.915832Z","caller":"traceutil/trace.go:171","msg":"trace[1860941659] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1316; }","duration":"448.380818ms","start":"2026-05-27T19:46:21.467434Z","end":"2026-05-27T19:46:21.915815Z","steps":["trace[1860941659] 'agreement among raft nodes before linearized reading' (duration: 448.076111ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.916245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.467407Z","time spent":"448.819236ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-27T19:46:21.915742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.753476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:46:21.916383Z","caller":"traceutil/trace.go:171","msg":"trace[1395557195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1316; }","duration":"374.4429ms","start":"2026-05-27T19:46:21.541913Z","end":"2026-05-27T19:46:21.916356Z","steps":["trace[1395557195] 'agreement among raft nodes before linearized reading' (duration: 373.756447ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:21.916389Z","caller":"traceutil/trace.go:171","msg":"trace[568623219] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"746.326319ms","start":"2026-05-27T19:46:21.168807Z","end":"2026-05-27T19:46:21.915134Z","steps":["trace[568623219] 'process raft request' (duration: 267.867186ms)","trace[568623219] 'compare' (duration: 477.673318ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:21.916475Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.541875Z","time spent":"374.589222ms","remote":"127.0.0.1:50006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-27T19:46:21.915868Z","caller":"traceutil/trace.go:171","msg":"trace[125410130] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"448.361757ms","start":"2026-05-27T19:46:21.467484Z","end":"2026-05-27T19:46:21.915846Z","steps":["trace[125410130] 'agreement among raft nodes before linearized reading' (duration: 448.064421ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.916601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.467452Z","time spent":"449.094681ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:21.916603Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.168776Z","time spent":"747.740978ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:46:21.915881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.957028ms","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-05-27T19:46:21.916775Z","caller":"traceutil/trace.go:171","msg":"trace[358489115] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1316; }","duration":"448.869716ms","start":"2026-05-27T19:46:21.46788Z","end":"2026-05-27T19:46:21.91675Z","steps":["trace[358489115] 'agreement among raft nodes before linearized reading' (duration: 447.914578ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:21.916826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.467857Z","time spent":"448.961278ms","remote":"127.0.0.1:50314","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-05-27T19:46:22.329973Z","caller":"traceutil/trace.go:171","msg":"trace[1609366231] transaction","detail":"{read_only:false; response_revision:1317; number_of_response:1; }","duration":"408.591127ms","start":"2026-05-27T19:46:21.921353Z","end":"2026-05-27T19:46:22.329944Z","steps":["trace[1609366231] 'process raft request' (duration: 408.432974ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:22.33012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921337Z","time spent":"408.71016ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-27T19:46:22.340533Z","caller":"traceutil/trace.go:171","msg":"trace[1849698702] transaction","detail":"{read_only:false; response_revision:1318; number_of_response:1; }","duration":"418.79349ms","start":"2026-05-27T19:46:21.921701Z","end":"2026-05-27T19:46:22.340495Z","steps":["trace[1849698702] 'process raft request' (duration: 418.476444ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:22.340572Z","caller":"traceutil/trace.go:171","msg":"trace[1118583696] transaction","detail":"{read_only:false; response_revision:1319; number_of_response:1; }","duration":"418.729249ms","start":"2026-05-27T19:46:21.921803Z","end":"2026-05-27T19:46:22.340533Z","steps":["trace[1118583696] 'process raft request' (duration: 418.572586ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:22.34057Z","caller":"traceutil/trace.go:171","msg":"trace[2126122757] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"418.637247ms","start":"2026-05-27T19:46:21.921913Z","end":"2026-05-27T19:46:22.340551Z","steps":["trace[2126122757] 'process raft request' (duration: 418.546855ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:22.340727Z","caller":"traceutil/trace.go:171","msg":"trace[1179291791] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"418.707819ms","start":"2026-05-27T19:46:21.921974Z","end":"2026-05-27T19:46:22.340682Z","steps":["trace[1179291791] 'process raft request' (duration: 418.525125ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:22.340766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921674Z","time spent":"418.958093ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:22.340785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921795Z","time spent":"418.859221ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:22.34082Z","caller":"traceutil/trace.go:171","msg":"trace[1248448519] transaction","detail":"{read_only:false; response_revision:1322; number_of_response:1; }","duration":"418.834191ms","start":"2026-05-27T19:46:21.921971Z","end":"2026-05-27T19:46:22.340806Z","steps":["trace[1248448519] 'process raft request' (duration: 418.615847ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:22.340841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.963783ms","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-05-27T19:46:22.340881Z","caller":"traceutil/trace.go:171","msg":"trace[1646217112] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1322; }","duration":"419.026364ms","start":"2026-05-27T19:46:21.921844Z","end":"2026-05-27T19:46:22.340871Z","steps":["trace[1646217112] 'agreement among raft nodes before linearized reading' (duration: 418.856211ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:22.340869Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921933Z","time spent":"418.910193ms","remote":"127.0.0.1:50314","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-05-27T19:46:22.340884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921936Z","time spent":"418.872662ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-27T19:46:22.340765Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921904Z","time spent":"418.80758ms","remote":"127.0.0.1:50314","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-05-27T19:46:22.340914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:21.921836Z","time spent":"419.068145ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":495,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T19:46:22.340664Z","caller":"traceutil/trace.go:171","msg":"trace[1522256325] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1366; }","duration":"418.7782ms","start":"2026-05-27T19:46:21.921868Z","end":"2026-05-27T19:46:22.340646Z","steps":["trace[1522256325] 'read index received' (duration: 407.982675ms)","trace[1522256325] 'applied index is now lower than readState.Index' (duration: 10.794645ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:32.867594Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776133867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:46:33.368171Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3522833515776133867,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-27T19:46:33.427551Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15857285s","expected-duration":"1s"} {"level":"info","ts":"2026-05-27T19:46:33.428173Z","caller":"traceutil/trace.go:171","msg":"trace[1297640502] transaction","detail":"{read_only:false; response_revision:1392; number_of_response:1; }","duration":"1.159298703s","start":"2026-05-27T19:46:32.268852Z","end":"2026-05-27T19:46:33.42815Z","steps":["trace[1297640502] 'process raft request' (duration: 1.159023618s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.428451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.26882Z","time spent":"1.159487086s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:33.69003Z","caller":"traceutil/trace.go:171","msg":"trace[1521611704] transaction","detail":"{read_only:false; response_revision:1393; number_of_response:1; }","duration":"687.573373ms","start":"2026-05-27T19:46:33.002408Z","end":"2026-05-27T19:46:33.689981Z","steps":["trace[1521611704] 'process raft request' (duration: 687.287338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.690344Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32288831s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:473"} {"level":"warn","ts":"2026-05-27T19:46:33.690393Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:33.002378Z","time spent":"687.752517ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-27T19:46:33.690056Z","caller":"traceutil/trace.go:171","msg":"trace[1194005476] transaction","detail":"{read_only:false; response_revision:1394; number_of_response:1; }","duration":"509.126172ms","start":"2026-05-27T19:46:33.180872Z","end":"2026-05-27T19:46:33.689999Z","steps":["trace[1194005476] 'process raft request' (duration: 508.99505ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:33.690454Z","caller":"traceutil/trace.go:171","msg":"trace[1450106032] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1394; }","duration":"1.323063304s","start":"2026-05-27T19:46:32.367364Z","end":"2026-05-27T19:46:33.690427Z","steps":["trace[1450106032] 'agreement among raft nodes before linearized reading' (duration: 1.322709077s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.690505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.367336Z","time spent":"1.323159955s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":496,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-05-27T19:46:33.690646Z","caller":"traceutil/trace.go:171","msg":"trace[1801929334] linearizableReadLoop","detail":"{readStateIndex:1446; appliedIndex:1443; }","duration":"1.322492572s","start":"2026-05-27T19:46:32.367428Z","end":"2026-05-27T19:46:33.68992Z","steps":["trace[1801929334] 'read index received' (duration: 1.060423392s)","trace[1801929334] 'applied index is now lower than readState.Index' (duration: 262.06803ms)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:46:33.69071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:33.180835Z","time spent":"509.630192ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-27T19:46:33.690933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.849324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-27T19:46:33.690956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.301613968s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-05-27T19:46:33.690991Z","caller":"traceutil/trace.go:171","msg":"trace[1405093856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1394; }","duration":"149.979766ms","start":"2026-05-27T19:46:33.540993Z","end":"2026-05-27T19:46:33.690973Z","steps":["trace[1405093856] 'agreement among raft nodes before linearized reading' (duration: 149.883214ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:33.691001Z","caller":"traceutil/trace.go:171","msg":"trace[1980715473] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1394; }","duration":"1.30170749s","start":"2026-05-27T19:46:32.389281Z","end":"2026-05-27T19:46:33.690988Z","steps":["trace[1980715473] 'agreement among raft nodes before linearized reading' (duration: 1.301511786s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"571.494189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-27T19:46:33.691144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299077317s","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-05-27T19:46:33.691185Z","caller":"traceutil/trace.go:171","msg":"trace[1476652099] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1394; }","duration":"571.675993ms","start":"2026-05-27T19:46:33.119443Z","end":"2026-05-27T19:46:33.691119Z","steps":["trace[1476652099] 'agreement among raft nodes before linearized reading' (duration: 571.350887ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:46:33.69123Z","caller":"traceutil/trace.go:171","msg":"trace[770802888] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1394; }","duration":"1.299314222s","start":"2026-05-27T19:46:32.391884Z","end":"2026-05-27T19:46:33.691199Z","steps":["trace[770802888] 'agreement among raft nodes before linearized reading' (duration: 1.2991795s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691274Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.391857Z","time spent":"1.299407663s","remote":"127.0.0.1:50314","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-05-27T19:46:33.691257Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:33.119423Z","time spent":"571.798646ms","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-27T19:46:33.6914Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.21925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-05-27T19:46:33.691413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.423926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:106520"} {"level":"info","ts":"2026-05-27T19:46:33.691441Z","caller":"traceutil/trace.go:171","msg":"trace[1754205227] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:1394; }","duration":"393.489048ms","start":"2026-05-27T19:46:33.297944Z","end":"2026-05-27T19:46:33.691433Z","steps":["trace[1754205227] 'agreement among raft nodes before linearized reading' (duration: 393.399906ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:33.297921Z","time spent":"393.542169ms","remote":"127.0.0.1:50100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":106543,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"warn","ts":"2026-05-27T19:46:33.691391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.998308ms","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-05-27T19:46:33.69151Z","caller":"traceutil/trace.go:171","msg":"trace[1054870157] range","detail":"{range_begin:/registry/leases/kube-system/external-resizer-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1394; }","duration":"141.173462ms","start":"2026-05-27T19:46:33.550323Z","end":"2026-05-27T19:46:33.691497Z","steps":["trace[1054870157] 'agreement among raft nodes before linearized reading' (duration: 140.614871ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.389253Z","time spent":"1.301774621s","remote":"127.0.0.1:50314","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-05-27T19:46:33.691313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299525206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-05-27T19:46:33.691313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.057251598s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-27T19:46:33.69168Z","caller":"traceutil/trace.go:171","msg":"trace[631137152] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1394; }","duration":"1.299895233s","start":"2026-05-27T19:46:32.391757Z","end":"2026-05-27T19:46:33.691652Z","steps":["trace[631137152] 'agreement among raft nodes before linearized reading' (duration: 1.299493215s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.391743Z","time spent":"1.300077716s","remote":"127.0.0.1:50314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-27T19:46:33.691675Z","caller":"traceutil/trace.go:171","msg":"trace[848415869] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1394; }","duration":"1.057668116s","start":"2026-05-27T19:46:32.633991Z","end":"2026-05-27T19:46:33.691659Z","steps":["trace[848415869] 'agreement among raft nodes before linearized reading' (duration: 1.057266698s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.691966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.633957Z","time spent":"1.058000493s","remote":"127.0.0.1:33648","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":4,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-27T19:46:33.692024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280440327s","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-05-27T19:46:33.692056Z","caller":"traceutil/trace.go:171","msg":"trace[1931280841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1394; }","duration":"1.280500598s","start":"2026-05-27T19:46:32.411546Z","end":"2026-05-27T19:46:33.692047Z","steps":["trace[1931280841] 'agreement among raft nodes before linearized reading' (duration: 1.279249495s)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.692077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:32.411534Z","time spent":"1.28053815s","remote":"127.0.0.1:50314","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-05-27T19:46:33.691438Z","caller":"traceutil/trace.go:171","msg":"trace[502520925] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1394; }","duration":"411.288092ms","start":"2026-05-27T19:46:33.280137Z","end":"2026-05-27T19:46:33.691425Z","steps":["trace[502520925] 'agreement among raft nodes before linearized reading' (duration: 411.21917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:46:33.692615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-27T19:46:33.280108Z","time spent":"412.467085ms","remote":"127.0.0.1:50108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":141,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"info","ts":"2026-05-27T19:48:30.510802Z","caller":"traceutil/trace.go:171","msg":"trace[462692287] transaction","detail":"{read_only:false; response_revision:1932; number_of_response:1; }","duration":"121.926925ms","start":"2026-05-27T19:48:30.388823Z","end":"2026-05-27T19:48:30.51075Z","steps":["trace[462692287] 'process raft request' (duration: 36.114797ms)","trace[462692287] 'compare' (duration: 85.575424ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:30.510798Z","caller":"traceutil/trace.go:171","msg":"trace[525809848] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"121.529216ms","start":"2026-05-27T19:48:30.389235Z","end":"2026-05-27T19:48:30.510764Z","steps":["trace[525809848] 'process raft request' (duration: 121.436535ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:30.510955Z","caller":"traceutil/trace.go:171","msg":"trace[534825777] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2006; }","duration":"118.229262ms","start":"2026-05-27T19:48:30.3927Z","end":"2026-05-27T19:48:30.510929Z","steps":["trace[534825777] 'read index received' (duration: 32.272081ms)","trace[534825777] 'applied index is now lower than readState.Index' (duration: 85.955141ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:30.51114Z","caller":"traceutil/trace.go:171","msg":"trace[309516751] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"121.746891ms","start":"2026-05-27T19:48:30.389334Z","end":"2026-05-27T19:48:30.511081Z","steps":["trace[309516751] 'process raft request' (duration: 121.382973ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:30.511322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.594979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:48:30.511423Z","caller":"traceutil/trace.go:171","msg":"trace[246386270] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1934; }","duration":"118.731063ms","start":"2026-05-27T19:48:30.392678Z","end":"2026-05-27T19:48:30.511409Z","steps":["trace[246386270] 'agreement among raft nodes before linearized reading' (duration: 118.333085ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:30.62707Z","caller":"traceutil/trace.go:171","msg":"trace[522033855] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"110.773306ms","start":"2026-05-27T19:48:30.516246Z","end":"2026-05-27T19:48:30.627019Z","steps":["trace[522033855] 'process raft request' (duration: 110.499621ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:30.807772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.103055ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:48:30.807877Z","caller":"traceutil/trace.go:171","msg":"trace[465715199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1935; }","duration":"267.263387ms","start":"2026-05-27T19:48:30.540586Z","end":"2026-05-27T19:48:30.807849Z","steps":["trace[465715199] 'agreement among raft nodes before linearized reading' (duration: 86.387118ms)","trace[465715199] 'range keys from in-memory index tree' (duration: 180.716475ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:48:30.808735Z","caller":"traceutil/trace.go:171","msg":"trace[36043499] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"104.77777ms","start":"2026-05-27T19:48:30.703926Z","end":"2026-05-27T19:48:30.808704Z","steps":["trace[36043499] 'process raft request' (duration: 104.591946ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:56.862977Z","caller":"traceutil/trace.go:171","msg":"trace[1705349137] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"154.557024ms","start":"2026-05-27T19:48:56.708394Z","end":"2026-05-27T19:48:56.862951Z","steps":["trace[1705349137] 'process raft request' (duration: 154.408891ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:56.886604Z","caller":"traceutil/trace.go:171","msg":"trace[747567033] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"177.520212ms","start":"2026-05-27T19:48:56.709065Z","end":"2026-05-27T19:48:56.886585Z","steps":["trace[747567033] 'process raft request' (duration: 177.163775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:48:56.88694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.532884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-27T19:48:56.887025Z","caller":"traceutil/trace.go:171","msg":"trace[506162760] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2056; }","duration":"128.693517ms","start":"2026-05-27T19:48:56.758312Z","end":"2026-05-27T19:48:56.887005Z","steps":["trace[506162760] 'agreement among raft nodes before linearized reading' (duration: 128.344091ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:48:56.888073Z","caller":"traceutil/trace.go:171","msg":"trace[855468378] linearizableReadLoop","detail":"{readStateIndex:2137; appliedIndex:2135; }","duration":"128.064775ms","start":"2026-05-27T19:48:56.758379Z","end":"2026-05-27T19:48:56.886444Z","steps":["trace[855468378] 'read index received' (duration: 104.428983ms)","trace[855468378] 'applied index is now lower than readState.Index' (duration: 23.634202ms)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:25.289164Z","caller":"traceutil/trace.go:171","msg":"trace[427610977] linearizableReadLoop","detail":"{readStateIndex:2582; appliedIndex:2581; }","duration":"211.15698ms","start":"2026-05-27T19:50:25.077984Z","end":"2026-05-27T19:50:25.289141Z","steps":["trace[427610977] 'read index received' (duration: 211.074458ms)","trace[427610977] 'applied index is now lower than readState.Index' (duration: 81.622µs)"],"step_count":2} {"level":"warn","ts":"2026-05-27T19:50:25.289413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.844965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/validatingwebhookconfigurations/\" range_end:\"/registry/validatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-27T19:50:25.289471Z","caller":"traceutil/trace.go:171","msg":"trace[755727917] range","detail":"{range_begin:/registry/validatingwebhookconfigurations/; range_end:/registry/validatingwebhookconfigurations0; response_count:0; response_revision:2483; }","duration":"187.966378ms","start":"2026-05-27T19:50:25.101494Z","end":"2026-05-27T19:50:25.289461Z","steps":["trace[755727917] 'agreement among raft nodes before linearized reading' (duration: 187.859626ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:25.289235Z","caller":"traceutil/trace.go:171","msg":"trace[1681899359] transaction","detail":"{read_only:false; response_revision:2483; number_of_response:1; }","duration":"259.701304ms","start":"2026-05-27T19:50:25.029501Z","end":"2026-05-27T19:50:25.289202Z","steps":["trace[1681899359] 'process raft request' (duration: 259.48685ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:25.289724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.724781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-27T19:50:25.289792Z","caller":"traceutil/trace.go:171","msg":"trace[388975943] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2483; }","duration":"211.830903ms","start":"2026-05-27T19:50:25.077948Z","end":"2026-05-27T19:50:25.289779Z","steps":["trace[388975943] 'agreement among raft nodes before linearized reading' (duration: 211.308842ms)"],"step_count":1} {"level":"info","ts":"2026-05-27T19:50:35.457788Z","caller":"traceutil/trace.go:171","msg":"trace[1647119818] linearizableReadLoop","detail":"{readStateIndex:2638; appliedIndex:2637; }","duration":"104.504434ms","start":"2026-05-27T19:50:35.35326Z","end":"2026-05-27T19:50:35.457765Z","steps":["trace[1647119818] 'read index received' (duration: 104.207719ms)","trace[1647119818] 'applied index is now lower than readState.Index' (duration: 295.605µs)"],"step_count":2} {"level":"info","ts":"2026-05-27T19:50:35.457863Z","caller":"traceutil/trace.go:171","msg":"trace[209558855] transaction","detail":"{read_only:false; response_revision:2536; number_of_response:1; }","duration":"107.807648ms","start":"2026-05-27T19:50:35.350029Z","end":"2026-05-27T19:50:35.457836Z","steps":["trace[209558855] 'process raft request' (duration: 107.560353ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:35.458023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.744149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-05-27T19:50:35.458065Z","caller":"traceutil/trace.go:171","msg":"trace[515828718] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2536; }","duration":"104.83012ms","start":"2026-05-27T19:50:35.353224Z","end":"2026-05-27T19:50:35.458054Z","steps":["trace[515828718] 'agreement among raft nodes before linearized reading' (duration: 104.677457ms)"],"step_count":1} {"level":"warn","ts":"2026-05-27T19:50:35.458181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.802182ms","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-05-27T19:50:35.458266Z","caller":"traceutil/trace.go:171","msg":"trace[1399260022] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2536; }","duration":"101.918724ms","start":"2026-05-27T19:50:35.356326Z","end":"2026-05-27T19:50:35.458245Z","steps":["trace[1399260022] 'agreement among raft nodes before linearized reading' (duration: 101.667609ms)"],"step_count":1}