{"level":"warn","ts":"2026-02-22T05:02:35.772712Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-22T05:02:35.772795Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.209: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://162.253.55.209:2380","--initial-cluster=instance=https://162.253.55.209:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.209:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.209:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-22T05:02:35.772869Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-22T05:02:35.772881Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-22T05:02:35.7729Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-22T05:02:35.773609Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"]} {"level":"info","ts":"2026-02-22T05:02:35.773685Z","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://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.209:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-22T05:02:35.780146Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"6.222539ms"} {"level":"info","ts":"2026-02-22T05:02:35.79611Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"53de2bdd9aa7b793","cluster-id":"385802231390d48c"} {"level":"info","ts":"2026-02-22T05:02:35.79626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=()"} {"level":"info","ts":"2026-02-22T05:02:35.796321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 0"} {"level":"info","ts":"2026-02-22T05:02:35.796338Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 53de2bdd9aa7b793 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-22T05:02:35.796348Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became follower at term 1"} {"level":"info","ts":"2026-02-22T05:02:35.796407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"warn","ts":"2026-02-22T05:02:35.809433Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-22T05:02:35.811277Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-22T05:02:35.814037Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-22T05:02:35.818903Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"53de2bdd9aa7b793","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-22T05:02:35.819091Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"53de2bdd9aa7b793","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-22T05:02:35.819316Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-22T05:02:35.819387Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-22T05:02:35.819399Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-22T05:02:35.820106Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 switched to configuration voters=(6043315980760233875)"} {"level":"info","ts":"2026-02-22T05:02:35.82021Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","added-peer-id":"53de2bdd9aa7b793","added-peer-peer-urls":["https://162.253.55.209:2380"]} {"level":"info","ts":"2026-02-22T05:02:35.825653Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-22T05:02:35.825762Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-22T05:02:35.825845Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.209:2380"} {"level":"info","ts":"2026-02-22T05:02:35.825903Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"53de2bdd9aa7b793","initial-advertise-peer-urls":["https://162.253.55.209:2380"],"listen-peer-urls":["https://162.253.55.209:2380"],"advertise-client-urls":["https://162.253.55.209:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.209:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-22T05:02:35.82594Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-22T05:02:35.997354Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 is starting a new election at term 1"} {"level":"info","ts":"2026-02-22T05:02:35.997434Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-22T05:02:35.997483Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgPreVoteResp from 53de2bdd9aa7b793 at term 1"} {"level":"info","ts":"2026-02-22T05:02:35.997509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became candidate at term 2"} {"level":"info","ts":"2026-02-22T05:02:35.997541Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 received MsgVoteResp from 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-22T05:02:35.997563Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"53de2bdd9aa7b793 became leader at term 2"} {"level":"info","ts":"2026-02-22T05:02:35.997583Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 53de2bdd9aa7b793 elected leader 53de2bdd9aa7b793 at term 2"} {"level":"info","ts":"2026-02-22T05:02:35.998765Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T05:02:35.99981Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"53de2bdd9aa7b793","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.209:2379]}","request-path":"/0/members/53de2bdd9aa7b793/attributes","cluster-id":"385802231390d48c","publish-timeout":"7s"} {"level":"info","ts":"2026-02-22T05:02:35.99981Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T05:02:35.99997Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-22T05:02:36.000235Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-22T05:02:36.000254Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-22T05:02:36.001772Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"385802231390d48c","local-member-id":"53de2bdd9aa7b793","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T05:02:36.001875Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T05:02:36.001917Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-22T05:02:36.002493Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-22T05:02:36.003424Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.209:2379"} {"level":"info","ts":"2026-02-22T05:02:47.932464Z","caller":"traceutil/trace.go:171","msg":"trace[1457173843] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"127.059599ms","start":"2026-02-22T05:02:47.805378Z","end":"2026-02-22T05:02:47.932437Z","steps":["trace[1457173843] 'process raft request' (duration: 126.662636ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:02:48.249028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.851319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-22T05:02:48.249195Z","caller":"traceutil/trace.go:171","msg":"trace[1070825037] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:391; }","duration":"279.073036ms","start":"2026-02-22T05:02:47.970098Z","end":"2026-02-22T05:02:48.249171Z","steps":["trace[1070825037] 'range keys from in-memory index tree' (duration: 278.699236ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:02:48.249263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.077729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T05:02:48.249375Z","caller":"traceutil/trace.go:171","msg":"trace[2117704340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:391; }","duration":"106.241274ms","start":"2026-02-22T05:02:48.143108Z","end":"2026-02-22T05:02:48.249349Z","steps":["trace[2117704340] 'range keys from in-memory index tree' (duration: 105.96369ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:02:48.468299Z","caller":"traceutil/trace.go:171","msg":"trace[762108764] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"214.529647ms","start":"2026-02-22T05:02:48.253731Z","end":"2026-02-22T05:02:48.46826Z","steps":["trace[762108764] 'process raft request' (duration: 214.396366ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:02:48.702935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.241139ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:02:48.703063Z","caller":"traceutil/trace.go:171","msg":"trace[1893427739] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"449.207597ms","start":"2026-02-22T05:02:48.253833Z","end":"2026-02-22T05:02:48.703041Z","steps":["trace[1893427739] 'process raft request' (duration: 344.189155ms)","trace[1893427739] 'compare' (duration: 104.087416ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:02:48.703269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:02:48.253787Z","time spent":"449.338208ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:02:48.762879Z","caller":"traceutil/trace.go:171","msg":"trace[1512710772] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"124.714683ms","start":"2026-02-22T05:02:48.638136Z","end":"2026-02-22T05:02:48.762851Z","steps":["trace[1512710772] 'process raft request' (duration: 124.607824ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:02:59.506121Z","caller":"traceutil/trace.go:171","msg":"trace[1832972735] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"177.43242ms","start":"2026-02-22T05:02:59.328663Z","end":"2026-02-22T05:02:59.506095Z","steps":["trace[1832972735] 'process raft request' (duration: 171.234001ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:01.039023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098852858,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:01.395293Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.394603227s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T05:03:01.395653Z","caller":"traceutil/trace.go:171","msg":"trace[859235843] linearizableReadLoop","detail":"{readStateIndex:478; appliedIndex:477; }","duration":"857.12722ms","start":"2026-02-22T05:03:00.538498Z","end":"2026-02-22T05:03:01.395625Z","steps":["trace[859235843] 'read index received' (duration: 857.022416ms)","trace[859235843] 'applied index is now lower than readState.Index' (duration: 103.974µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:01.395723Z","caller":"traceutil/trace.go:171","msg":"trace[634415149] transaction","detail":"{read_only:false; response_revision:461; number_of_response:1; }","duration":"1.395103258s","start":"2026-02-22T05:03:00.000584Z","end":"2026-02-22T05:03:01.395687Z","steps":["trace[634415149] 'process raft request' (duration: 1.39489688s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:01.395839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"857.329738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-22T05:03:01.395878Z","caller":"traceutil/trace.go:171","msg":"trace[2019334525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:461; }","duration":"857.421733ms","start":"2026-02-22T05:03:00.538446Z","end":"2026-02-22T05:03:01.395868Z","steps":["trace[2019334525] 'agreement among raft nodes before linearized reading' (duration: 857.315399ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:01.395879Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:00.000559Z","time spent":"1.395229881s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:01.39591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:00.538427Z","time spent":"857.476329ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T05:03:01.896274Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098852861,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T05:03:02.249467Z","caller":"traceutil/trace.go:171","msg":"trace[677155813] linearizableReadLoop","detail":"{readStateIndex:480; appliedIndex:478; }","duration":"853.717167ms","start":"2026-02-22T05:03:01.39573Z","end":"2026-02-22T05:03:02.249447Z","steps":["trace[677155813] 'read index received' (duration: 814.601696ms)","trace[677155813] 'applied index is now lower than readState.Index' (duration: 39.114631ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:02.249702Z","caller":"traceutil/trace.go:171","msg":"trace[570780088] transaction","detail":"{read_only:false; response_revision:462; number_of_response:1; }","duration":"1.018889521s","start":"2026-02-22T05:03:01.230798Z","end":"2026-02-22T05:03:02.249688Z","steps":["trace[570780088] 'process raft request' (duration: 979.592ms)","trace[570780088] 'compare' (duration: 38.920602ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:02.24981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:01.230773Z","time spent":"1.018966897s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:02.249815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.480810393s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-22T05:03:02.249904Z","caller":"traceutil/trace.go:171","msg":"trace[38586758] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:463; }","duration":"1.480929116s","start":"2026-02-22T05:03:00.768947Z","end":"2026-02-22T05:03:02.249876Z","steps":["trace[38586758] 'agreement among raft nodes before linearized reading' (duration: 1.480756936s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:02.249981Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:00.768919Z","time spent":"1.48105177s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T05:03:02.249974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.744811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T05:03:02.250103Z","caller":"traceutil/trace.go:171","msg":"trace[411893994] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:463; }","duration":"695.919712ms","start":"2026-02-22T05:03:01.554158Z","end":"2026-02-22T05:03:02.250078Z","steps":["trace[411893994] 'agreement among raft nodes before linearized reading' (duration: 695.710863ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:02.25Z","caller":"traceutil/trace.go:171","msg":"trace[2063216030] transaction","detail":"{read_only:false; response_revision:463; number_of_response:1; }","duration":"846.651854ms","start":"2026-02-22T05:03:01.403311Z","end":"2026-02-22T05:03:02.249962Z","steps":["trace[2063216030] 'process raft request' (duration: 846.101575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:02.250152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.397347797s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T05:03:02.250216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:01.403284Z","time spent":"846.868422ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:02.250241Z","caller":"traceutil/trace.go:171","msg":"trace[1709595753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:463; }","duration":"1.39747458s","start":"2026-02-22T05:03:00.85275Z","end":"2026-02-22T05:03:02.250224Z","steps":["trace[1709595753] 'agreement among raft nodes before linearized reading' (duration: 1.397325608s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:02.250296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:00.852733Z","time spent":"1.397549865s","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:03:02.250162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:01.554106Z","time spent":"696.041185ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T05:03:07.116706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.046742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4021"} {"level":"info","ts":"2026-02-22T05:03:07.116807Z","caller":"traceutil/trace.go:171","msg":"trace[983886714] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:507; }","duration":"338.204525ms","start":"2026-02-22T05:03:06.778584Z","end":"2026-02-22T05:03:07.116789Z","steps":["trace[983886714] 'range keys from in-memory index tree' (duration: 337.733456ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:07.116852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:06.778566Z","time spent":"338.276831ms","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-22T05:03:07.639459Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098852954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:08.139998Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098852954,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:08.640528Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098852954,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T05:03:08.815045Z","caller":"traceutil/trace.go:171","msg":"trace[944464745] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"1.689716883s","start":"2026-02-22T05:03:07.125301Z","end":"2026-02-22T05:03:08.815018Z","steps":["trace[944464745] 'process raft request' (duration: 1.689516682s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:08.8152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:07.12528Z","time spent":"1.689846377s","remote":"127.0.0.1:38576","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4213,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:09.139562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000804224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-22T05:03:09.139734Z","caller":"traceutil/trace.go:171","msg":"trace[1148726457] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.001042083s","start":"2026-02-22T05:03:07.138664Z","end":"2026-02-22T05:03:09.139706Z","steps":["trace[1148726457] 'agreement among raft nodes before linearized reading' (duration: 2.000836812s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:09.139806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:07.138638Z","time spent":"2.001150159s","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/22 05:03:09 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-22T05:03:09.855639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000162483s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-22T05:03:09.8557Z","caller":"traceutil/trace.go:171","msg":"trace[871065838] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000272808s","start":"2026-02-22T05:03:07.855411Z","end":"2026-02-22T05:03:09.855684Z","steps":["trace[871065838] 'agreement among raft nodes before linearized reading' (duration: 2.000196632s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:09.855729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:07.855393Z","time spent":"2.000330826s","remote":"127.0.0.1:38418","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:03:10.308156Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.000864376s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:10.308256Z","caller":"traceutil/trace.go:171","msg":"trace[1729319603] linearizableReadLoop","detail":"{readStateIndex:527; appliedIndex:525; }","duration":"3.169520457s","start":"2026-02-22T05:03:07.138719Z","end":"2026-02-22T05:03:10.30824Z","steps":["trace[1729319603] 'read index received' (duration: 1.676249603s)","trace[1729319603] 'applied index is now lower than readState.Index' (duration: 1.493269704s)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:10.308294Z","caller":"traceutil/trace.go:171","msg":"trace[1756815812] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"1.724192515s","start":"2026-02-22T05:03:08.58408Z","end":"2026-02-22T05:03:10.308273Z","steps":["trace[1756815812] 'process raft request' (duration: 723.123817ms)","trace[1756815812] 'compare' (duration: 1.000697413s)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:10.30842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:08.584052Z","time spent":"1.724320311s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:10.703003Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.395921421s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T05:03:10.772754Z","caller":"traceutil/trace.go:171","msg":"trace[19549022] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"464.437403ms","start":"2026-02-22T05:03:10.30828Z","end":"2026-02-22T05:03:10.772718Z","steps":["trace[19549022] 'read index received' (duration: 394.844275ms)","trace[19549022] 'applied index is now lower than readState.Index' (duration: 69.591798ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:10.772886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.46173429s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.209\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-02-22T05:03:10.772982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.328624949s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-22T05:03:10.773001Z","caller":"traceutil/trace.go:171","msg":"trace[819827778] range","detail":"{range_begin:/registry/masterleases/162.253.55.209; range_end:; response_count:1; response_revision:510; }","duration":"1.461895364s","start":"2026-02-22T05:03:09.311086Z","end":"2026-02-22T05:03:10.772982Z","steps":["trace[819827778] 'agreement among raft nodes before linearized reading' (duration: 1.461644374s)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:10.773029Z","caller":"traceutil/trace.go:171","msg":"trace[138048821] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:510; }","duration":"3.328766564s","start":"2026-02-22T05:03:07.444252Z","end":"2026-02-22T05:03:10.773018Z","steps":["trace[138048821] 'agreement among raft nodes before linearized reading' (duration: 3.328564092s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:10.773055Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:09.311067Z","time spent":"1.46198059s","remote":"127.0.0.1:38426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/162.253.55.209\" "} {"level":"warn","ts":"2026-02-22T05:03:10.773071Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:07.44423Z","time spent":"3.328831511s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-22T05:03:10.773365Z","caller":"traceutil/trace.go:171","msg":"trace[1870211586] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"1.63120366s","start":"2026-02-22T05:03:09.14215Z","end":"2026-02-22T05:03:10.773354Z","steps":["trace[1870211586] 'process raft request' (duration: 1.561040305s)","trace[1870211586] 'compare' (duration: 69.398526ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:10.773413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.447597252s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-22T05:03:10.773441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:09.142126Z","time spent":"1.631271057s","remote":"127.0.0.1:38484","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":758,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T05:03:10.773445Z","caller":"traceutil/trace.go:171","msg":"trace[123420886] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:510; }","duration":"2.447669939s","start":"2026-02-22T05:03:08.325765Z","end":"2026-02-22T05:03:10.773435Z","steps":["trace[123420886] 'agreement among raft nodes before linearized reading' (duration: 2.447576922s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:10.773474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:08.325747Z","time spent":"2.447721056s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T05:03:10.773561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"911.727281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:03:10.773596Z","caller":"traceutil/trace.go:171","msg":"trace[1307771195] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:510; }","duration":"911.783189ms","start":"2026-02-22T05:03:09.861798Z","end":"2026-02-22T05:03:10.773581Z","steps":["trace[1307771195] 'agreement among raft nodes before linearized reading' (duration: 911.74097ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:10.773635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:09.861784Z","time spent":"911.841497ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:03:10.773641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.449154003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-22T05:03:10.773686Z","caller":"traceutil/trace.go:171","msg":"trace[1695133397] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:510; }","duration":"2.449222011s","start":"2026-02-22T05:03:08.324455Z","end":"2026-02-22T05:03:10.773677Z","steps":["trace[1695133397] 'agreement among raft nodes before linearized reading' (duration: 2.449119635s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:10.773714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:08.324443Z","time spent":"2.449264768s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T05:03:10.773865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.488263724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-02-22T05:03:10.773889Z","caller":"traceutil/trace.go:171","msg":"trace[63305618] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:510; }","duration":"2.488325152s","start":"2026-02-22T05:03:08.285557Z","end":"2026-02-22T05:03:10.773882Z","steps":["trace[63305618] 'agreement among raft nodes before linearized reading' (duration: 2.488256315s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:10.773918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:08.28553Z","time spent":"2.488381909s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-22T05:03:11.012354Z","caller":"traceutil/trace.go:171","msg":"trace[1224078692] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"233.704023ms","start":"2026-02-22T05:03:10.778622Z","end":"2026-02-22T05:03:11.012326Z","steps":["trace[1224078692] 'process raft request' (duration: 233.641395ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:11.012417Z","caller":"traceutil/trace.go:171","msg":"trace[2075054906] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"235.239711ms","start":"2026-02-22T05:03:10.777126Z","end":"2026-02-22T05:03:11.012366Z","steps":["trace[2075054906] 'process raft request' (duration: 216.53941ms)","trace[2075054906] 'compare' (duration: 18.303617ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:11.012543Z","caller":"traceutil/trace.go:171","msg":"trace[1397262923] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"234.420853ms","start":"2026-02-22T05:03:10.778062Z","end":"2026-02-22T05:03:11.012483Z","steps":["trace[1397262923] 'process raft request' (duration: 234.169503ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:11.012658Z","caller":"traceutil/trace.go:171","msg":"trace[1602451658] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"232.189055ms","start":"2026-02-22T05:03:10.780445Z","end":"2026-02-22T05:03:11.012634Z","steps":["trace[1602451658] 'process raft request' (duration: 231.846848ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:11.012506Z","caller":"traceutil/trace.go:171","msg":"trace[997076100] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"234.431353ms","start":"2026-02-22T05:03:10.778Z","end":"2026-02-22T05:03:11.012431Z","steps":["trace[997076100] 'process raft request' (duration: 234.170924ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:11.06668Z","caller":"traceutil/trace.go:171","msg":"trace[1232599029] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"265.923115ms","start":"2026-02-22T05:03:10.800743Z","end":"2026-02-22T05:03:11.066666Z","steps":["trace[1232599029] 'process raft request' (duration: 265.834839ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:12.040945Z","caller":"traceutil/trace.go:171","msg":"trace[1933717456] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"100.930297ms","start":"2026-02-22T05:03:11.939992Z","end":"2026-02-22T05:03:12.040923Z","steps":["trace[1933717456] 'process raft request' (duration: 100.792392ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:12.291247Z","caller":"traceutil/trace.go:171","msg":"trace[93161586] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"246.431723ms","start":"2026-02-22T05:03:12.044785Z","end":"2026-02-22T05:03:12.291217Z","steps":["trace[93161586] 'process raft request' (duration: 183.074885ms)","trace[93161586] 'compare' (duration: 63.219273ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:12.298645Z","caller":"traceutil/trace.go:171","msg":"trace[391779810] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"247.507043ms","start":"2026-02-22T05:03:12.051114Z","end":"2026-02-22T05:03:12.298621Z","steps":["trace[391779810] 'process raft request' (duration: 247.441576ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:12.298773Z","caller":"traceutil/trace.go:171","msg":"trace[1680620458] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"253.690683ms","start":"2026-02-22T05:03:12.045023Z","end":"2026-02-22T05:03:12.298714Z","steps":["trace[1680620458] 'process raft request' (duration: 253.415483ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:12.594921Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.635357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:12.595293Z","caller":"traceutil/trace.go:171","msg":"trace[1928182968] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"134.259684ms","start":"2026-02-22T05:03:12.461015Z","end":"2026-02-22T05:03:12.595275Z","steps":["trace[1928182968] 'process raft request' (duration: 134.187556ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:12.595368Z","caller":"traceutil/trace.go:171","msg":"trace[777674570] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"233.461627ms","start":"2026-02-22T05:03:12.361868Z","end":"2026-02-22T05:03:12.59533Z","steps":["trace[777674570] 'process raft request' (duration: 125.349778ms)","trace[777674570] 'compare' (duration: 107.513421ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:13.01932Z","caller":"traceutil/trace.go:171","msg":"trace[245073267] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"418.862698ms","start":"2026-02-22T05:03:12.600421Z","end":"2026-02-22T05:03:13.019283Z","steps":["trace[245073267] 'process raft request' (duration: 418.53052ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:13.019461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:12.600398Z","time spent":"418.999753ms","remote":"127.0.0.1:38742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1109,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T05:03:13.204959Z","caller":"traceutil/trace.go:171","msg":"trace[1893653764] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"180.571819ms","start":"2026-02-22T05:03:13.024359Z","end":"2026-02-22T05:03:13.204931Z","steps":["trace[1893653764] 'process raft request' (duration: 127.265437ms)","trace[1893653764] 'compare' (duration: 52.872517ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:13.204965Z","caller":"traceutil/trace.go:171","msg":"trace[339806570] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"180.064477ms","start":"2026-02-22T05:03:13.024876Z","end":"2026-02-22T05:03:13.20494Z","steps":["trace[339806570] 'process raft request' (duration: 179.99667ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:13.205319Z","caller":"traceutil/trace.go:171","msg":"trace[2009768670] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"180.671765ms","start":"2026-02-22T05:03:13.024628Z","end":"2026-02-22T05:03:13.2053Z","steps":["trace[2009768670] 'process raft request' (duration: 180.202372ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:13.205505Z","caller":"traceutil/trace.go:171","msg":"trace[676695106] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"180.752972ms","start":"2026-02-22T05:03:13.024599Z","end":"2026-02-22T05:03:13.205352Z","steps":["trace[676695106] 'process raft request' (duration: 180.175843ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:13.545685Z","caller":"traceutil/trace.go:171","msg":"trace[1324218278] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"257.972962ms","start":"2026-02-22T05:03:13.287676Z","end":"2026-02-22T05:03:13.545649Z","steps":["trace[1324218278] 'process raft request' (duration: 218.18933ms)","trace[1324218278] 'compare' (duration: 39.450274ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:13.670149Z","caller":"traceutil/trace.go:171","msg":"trace[1315710319] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"112.689106ms","start":"2026-02-22T05:03:13.55744Z","end":"2026-02-22T05:03:13.670129Z","steps":["trace[1315710319] 'process raft request' (duration: 95.899174ms)","trace[1315710319] 'compare' (duration: 16.684646ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:13.762521Z","caller":"traceutil/trace.go:171","msg":"trace[675887715] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:551; }","duration":"112.0322ms","start":"2026-02-22T05:03:13.650463Z","end":"2026-02-22T05:03:13.762495Z","steps":["trace[675887715] 'read index received' (duration: 2.892017ms)","trace[675887715] 'applied index is now lower than readState.Index' (duration: 109.138773ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:13.762546Z","caller":"traceutil/trace.go:171","msg":"trace[581777334] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"112.785103ms","start":"2026-02-22T05:03:13.649735Z","end":"2026-02-22T05:03:13.76252Z","steps":["trace[581777334] 'process raft request' (duration: 112.592669ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:13.762744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.254452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/local-path-storage/\" range_end:\"/registry/limitranges/local-path-storage0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:03:13.76278Z","caller":"traceutil/trace.go:171","msg":"trace[368395246] range","detail":"{range_begin:/registry/limitranges/local-path-storage/; range_end:/registry/limitranges/local-path-storage0; response_count:0; response_revision:534; }","duration":"112.3091ms","start":"2026-02-22T05:03:13.650458Z","end":"2026-02-22T05:03:13.762767Z","steps":["trace[368395246] 'agreement among raft nodes before linearized reading' (duration: 112.159985ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:14.263156Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853005,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T05:03:14.281949Z","caller":"traceutil/trace.go:171","msg":"trace[786923245] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"627.495416ms","start":"2026-02-22T05:03:13.65443Z","end":"2026-02-22T05:03:14.281925Z","steps":["trace[786923245] 'process raft request' (duration: 627.128607ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:14.282078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:13.654398Z","time spent":"627.610302ms","remote":"127.0.0.1:38830","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3328,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:14.293156Z","caller":"traceutil/trace.go:171","msg":"trace[1728273780] linearizableReadLoop","detail":"{readStateIndex:555; appliedIndex:553; }","duration":"530.555257ms","start":"2026-02-22T05:03:13.762588Z","end":"2026-02-22T05:03:14.293143Z","steps":["trace[1728273780] 'read index received' (duration: 518.913412ms)","trace[1728273780] 'applied index is now lower than readState.Index' (duration: 11.640735ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:14.293299Z","caller":"traceutil/trace.go:171","msg":"trace[1217898203] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"527.909621ms","start":"2026-02-22T05:03:13.765382Z","end":"2026-02-22T05:03:14.293292Z","steps":["trace[1217898203] 'process raft request' (duration: 527.673409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:14.293347Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:13.765363Z","time spent":"527.9533ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2550,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-22T05:03:14.293577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.959647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-22T05:03:14.293613Z","caller":"traceutil/trace.go:171","msg":"trace[428422702] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:536; }","duration":"566.028394ms","start":"2026-02-22T05:03:13.727576Z","end":"2026-02-22T05:03:14.293604Z","steps":["trace[428422702] 'agreement among raft nodes before linearized reading' (duration: 565.864089ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:14.293634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:13.727554Z","time spent":"566.074743ms","remote":"127.0.0.1:38498","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":12749,"request content":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" "} {"level":"warn","ts":"2026-02-22T05:03:14.293748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"440.08733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:03:14.293766Z","caller":"traceutil/trace.go:171","msg":"trace[1307378839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:536; }","duration":"440.161407ms","start":"2026-02-22T05:03:13.853599Z","end":"2026-02-22T05:03:14.29376Z","steps":["trace[1307378839] 'agreement among raft nodes before linearized reading' (duration: 440.131178ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:14.293783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:13.853572Z","time spent":"440.206146ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T05:03:14.446063Z","caller":"traceutil/trace.go:171","msg":"trace[1009679837] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"148.821804ms","start":"2026-02-22T05:03:14.297165Z","end":"2026-02-22T05:03:14.445987Z","steps":["trace[1009679837] 'process raft request' (duration: 86.145304ms)","trace[1009679837] 'compare' (duration: 62.468147ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:14.522628Z","caller":"traceutil/trace.go:171","msg":"trace[212435723] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"221.528665ms","start":"2026-02-22T05:03:14.301076Z","end":"2026-02-22T05:03:14.522604Z","steps":["trace[212435723] 'process raft request' (duration: 221.431357ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:14.522654Z","caller":"traceutil/trace.go:171","msg":"trace[324623840] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"222.026747ms","start":"2026-02-22T05:03:14.300588Z","end":"2026-02-22T05:03:14.522614Z","steps":["trace[324623840] 'process raft request' (duration: 221.874382ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:14.522766Z","caller":"traceutil/trace.go:171","msg":"trace[1053984256] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"222.26365ms","start":"2026-02-22T05:03:14.300459Z","end":"2026-02-22T05:03:14.522722Z","steps":["trace[1053984256] 'process raft request' (duration: 221.868752ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:14.52305Z","caller":"traceutil/trace.go:171","msg":"trace[1733445717] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"221.771907ms","start":"2026-02-22T05:03:14.301267Z","end":"2026-02-22T05:03:14.523039Z","steps":["trace[1733445717] 'process raft request' (duration: 221.290702ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:14.607341Z","caller":"traceutil/trace.go:171","msg":"trace[1188318196] linearizableReadLoop","detail":"{readStateIndex:560; appliedIndex:555; }","duration":"256.230275ms","start":"2026-02-22T05:03:14.351089Z","end":"2026-02-22T05:03:14.607319Z","steps":["trace[1188318196] 'read index received' (duration: 32.225246ms)","trace[1188318196] 'applied index is now lower than readState.Index' (duration: 224.003659ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:14.607566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.455868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/local-path-storage/\" range_end:\"/registry/secrets/local-path-storage0\" ","response":"range_response_count:1 size:12727"} {"level":"info","ts":"2026-02-22T05:03:14.607599Z","caller":"traceutil/trace.go:171","msg":"trace[1095873567] range","detail":"{range_begin:/registry/secrets/local-path-storage/; range_end:/registry/secrets/local-path-storage0; response_count:1; response_revision:541; }","duration":"256.505226ms","start":"2026-02-22T05:03:14.351084Z","end":"2026-02-22T05:03:14.607589Z","steps":["trace[1095873567] 'agreement among raft nodes before linearized reading' (duration: 256.364341ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:14.82412Z","caller":"traceutil/trace.go:171","msg":"trace[596153108] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"204.407266ms","start":"2026-02-22T05:03:14.619696Z","end":"2026-02-22T05:03:14.824104Z","steps":["trace[596153108] 'process raft request' (duration: 204.29048ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:15.11624Z","caller":"traceutil/trace.go:171","msg":"trace[807819554] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"288.351563ms","start":"2026-02-22T05:03:14.827867Z","end":"2026-02-22T05:03:15.116219Z","steps":["trace[807819554] 'process raft request' (duration: 287.81467ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:17.543692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.94668ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:17.543863Z","caller":"traceutil/trace.go:171","msg":"trace[1907235461] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"297.437972ms","start":"2026-02-22T05:03:17.246412Z","end":"2026-02-22T05:03:17.54385Z","steps":["trace[1907235461] 'process raft request' (duration: 297.410303ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:17.543991Z","caller":"traceutil/trace.go:171","msg":"trace[727251302] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"297.708914ms","start":"2026-02-22T05:03:17.246274Z","end":"2026-02-22T05:03:17.543983Z","steps":["trace[727251302] 'process raft request' (duration: 71.400105ms)","trace[727251302] 'compare' (duration: 225.828663ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:17.544049Z","caller":"traceutil/trace.go:171","msg":"trace[1110622063] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"297.637635ms","start":"2026-02-22T05:03:17.246406Z","end":"2026-02-22T05:03:17.544044Z","steps":["trace[1110622063] 'process raft request' (duration: 297.381283ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:21.331857Z","caller":"traceutil/trace.go:171","msg":"trace[122813683] linearizableReadLoop","detail":"{readStateIndex:583; appliedIndex:582; }","duration":"143.041201ms","start":"2026-02-22T05:03:21.188788Z","end":"2026-02-22T05:03:21.331829Z","steps":["trace[122813683] 'read index received' (duration: 142.812407ms)","trace[122813683] 'applied index is now lower than readState.Index' (duration: 227.734µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:21.331936Z","caller":"traceutil/trace.go:171","msg":"trace[105047923] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"444.475877ms","start":"2026-02-22T05:03:20.887417Z","end":"2026-02-22T05:03:21.331893Z","steps":["trace[105047923] 'process raft request' (duration: 444.188294ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:21.332108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.295006ms","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-02-22T05:03:21.332109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:20.887388Z","time spent":"444.625063ms","remote":"127.0.0.1:38590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:21.332149Z","caller":"traceutil/trace.go:171","msg":"trace[392475687] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:561; }","duration":"143.389823ms","start":"2026-02-22T05:03:21.188746Z","end":"2026-02-22T05:03:21.332135Z","steps":["trace[392475687] 'agreement among raft nodes before linearized reading' (duration: 143.187088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:21.482852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.173ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:21.483009Z","caller":"traceutil/trace.go:171","msg":"trace[162314355] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"146.942581ms","start":"2026-02-22T05:03:21.33604Z","end":"2026-02-22T05:03:21.482983Z","steps":["trace[162314355] 'process raft request' (duration: 13.491038ms)","trace[162314355] 'compare' (duration: 133.021894ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:22.354495Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853072,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-22T05:03:22.407339Z","caller":"traceutil/trace.go:171","msg":"trace[899466236] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"844.396405ms","start":"2026-02-22T05:03:21.562917Z","end":"2026-02-22T05:03:22.407313Z","steps":["trace[899466236] 'process raft request' (duration: 844.221009ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:22.407522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:21.5629Z","time spent":"844.523172ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:22.42337Z","caller":"traceutil/trace.go:171","msg":"trace[300887817] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"859.493999ms","start":"2026-02-22T05:03:21.563862Z","end":"2026-02-22T05:03:22.423356Z","steps":["trace[300887817] 'process raft request' (duration: 859.364382ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:22.423469Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:21.563841Z","time spent":"859.574777ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:22.423515Z","caller":"traceutil/trace.go:171","msg":"trace[475609510] linearizableReadLoop","detail":"{readStateIndex:587; appliedIndex:584; }","duration":"569.838867ms","start":"2026-02-22T05:03:21.853653Z","end":"2026-02-22T05:03:22.423492Z","steps":["trace[475609510] 'read index received' (duration: 553.487002ms)","trace[475609510] 'applied index is now lower than readState.Index' (duration: 16.345315ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:22.423606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"569.954704ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:03:22.423626Z","caller":"traceutil/trace.go:171","msg":"trace[947396550] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:565; }","duration":"570.047802ms","start":"2026-02-22T05:03:21.853571Z","end":"2026-02-22T05:03:22.423619Z","steps":["trace[947396550] 'agreement among raft nodes before linearized reading' (duration: 569.982873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:22.423644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:21.853527Z","time spent":"570.112481ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-22T05:03:22.423843Z","caller":"traceutil/trace.go:171","msg":"trace[1148780496] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"858.399434ms","start":"2026-02-22T05:03:21.565403Z","end":"2026-02-22T05:03:22.423803Z","steps":["trace[1148780496] 'process raft request' (duration: 857.910325ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:22.423936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:21.565392Z","time spent":"858.481712ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:23.988724Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853078,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:24.246969Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.291101554s","expected-duration":"1s"} {"level":"info","ts":"2026-02-22T05:03:24.247402Z","caller":"traceutil/trace.go:171","msg":"trace[1171753579] linearizableReadLoop","detail":"{readStateIndex:588; appliedIndex:587; }","duration":"759.707426ms","start":"2026-02-22T05:03:23.487668Z","end":"2026-02-22T05:03:24.247375Z","steps":["trace[1171753579] 'read index received' (duration: 759.433451ms)","trace[1171753579] 'applied index is now lower than readState.Index' (duration: 272.835µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:24.247475Z","caller":"traceutil/trace.go:171","msg":"trace[131130906] transaction","detail":"{read_only:false; response_revision:566; number_of_response:1; }","duration":"1.291676373s","start":"2026-02-22T05:03:22.955764Z","end":"2026-02-22T05:03:24.247441Z","steps":["trace[131130906] 'process raft request' (duration: 1.291389798s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:24.247558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"393.552749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T05:03:24.247585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"759.911152ms","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-02-22T05:03:24.247602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:22.955742Z","time spent":"1.291793581s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:24.247622Z","caller":"traceutil/trace.go:171","msg":"trace[688804584] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:566; }","duration":"759.981291ms","start":"2026-02-22T05:03:23.487631Z","end":"2026-02-22T05:03:24.247612Z","steps":["trace[688804584] 'agreement among raft nodes before linearized reading' (duration: 759.843554ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:24.247624Z","caller":"traceutil/trace.go:171","msg":"trace[1595509280] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:566; }","duration":"393.675326ms","start":"2026-02-22T05:03:23.853933Z","end":"2026-02-22T05:03:24.247609Z","steps":["trace[1595509280] 'agreement among raft nodes before linearized reading' (duration: 393.561398ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:24.247664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:23.853912Z","time spent":"393.744625ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:03:24.247677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:23.487614Z","time spent":"760.041549ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":527,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-22T05:03:25.252805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.32057ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T05:03:25.253165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"856.067958ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:25.253945Z","caller":"traceutil/trace.go:171","msg":"trace[400251138] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:566; }","duration":"556.45787ms","start":"2026-02-22T05:03:24.697454Z","end":"2026-02-22T05:03:25.253912Z","steps":["trace[400251138] 'range keys from in-memory index tree' (duration: 555.304691ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:25.254114Z","caller":"traceutil/trace.go:171","msg":"trace[1340482835] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"998.900826ms","start":"2026-02-22T05:03:24.255142Z","end":"2026-02-22T05:03:25.254042Z","steps":["trace[1340482835] 'process raft request' (duration: 141.819406ms)","trace[1340482835] 'compare' (duration: 855.95459ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:25.254098Z","caller":"traceutil/trace.go:171","msg":"trace[2120479286] linearizableReadLoop","detail":"{readStateIndex:589; appliedIndex:588; }","duration":"841.117953ms","start":"2026-02-22T05:03:24.412924Z","end":"2026-02-22T05:03:25.254042Z","steps":["trace[2120479286] 'read index received' (duration: 51.989µs)","trace[2120479286] 'applied index is now lower than readState.Index' (duration: 841.060474ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:25.25423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:24.255129Z","time spent":"999.049344ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:25.254351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"841.405408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-22T05:03:25.254403Z","caller":"traceutil/trace.go:171","msg":"trace[1083720034] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:567; }","duration":"841.481846ms","start":"2026-02-22T05:03:24.412908Z","end":"2026-02-22T05:03:25.25439Z","steps":["trace[1083720034] 'agreement among raft nodes before linearized reading' (duration: 841.253561ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:25.254442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:24.412849Z","time spent":"841.585065ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-22T05:03:25.254499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.306354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-22T05:03:25.254549Z","caller":"traceutil/trace.go:171","msg":"trace[1119015564] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:567; }","duration":"826.398012ms","start":"2026-02-22T05:03:24.428139Z","end":"2026-02-22T05:03:25.254537Z","steps":["trace[1119015564] 'agreement among raft nodes before linearized reading' (duration: 826.235895ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:25.254578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:24.428122Z","time spent":"826.450531ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-22T05:03:25.254763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"826.623358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-22T05:03:25.254815Z","caller":"traceutil/trace.go:171","msg":"trace[1145024806] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:567; }","duration":"826.676037ms","start":"2026-02-22T05:03:24.428109Z","end":"2026-02-22T05:03:25.254785Z","steps":["trace[1145024806] 'agreement among raft nodes before linearized reading' (duration: 826.233225ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:25.254834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:24.428095Z","time spent":"826.734467ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-22T05:03:26.35471Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:26.85561Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:27.356213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":13228088620098853085,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-22T05:03:27.612853Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.496444892s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-22T05:03:27.754863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.756163ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:03:27.754978Z","caller":"traceutil/trace.go:171","msg":"trace[1574535492] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"2.495821957s","start":"2026-02-22T05:03:25.259137Z","end":"2026-02-22T05:03:27.754959Z","steps":["trace[1574535492] 'process raft request' (duration: 2.353894286s)","trace[1574535492] 'compare' (duration: 140.996365ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:27.755056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:25.259118Z","time spent":"2.495896366s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:27.854007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999341737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-22T05:03:27.854155Z","caller":"traceutil/trace.go:171","msg":"trace[1363125705] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999567594s","start":"2026-02-22T05:03:25.85456Z","end":"2026-02-22T05:03:27.854127Z","steps":["trace[1363125705] 'agreement among raft nodes before linearized reading' (duration: 1.999383296s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:27.85423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:25.854535Z","time spent":"1.999674481s","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/22 05:03:27 WARNING: [core] [Server #7] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"info","ts":"2026-02-22T05:03:27.997235Z","caller":"traceutil/trace.go:171","msg":"trace[363432953] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"2.737921002s","start":"2026-02-22T05:03:25.259269Z","end":"2026-02-22T05:03:27.99719Z","steps":["trace[363432953] 'process raft request' (duration: 2.737119813s)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:27.997237Z","caller":"traceutil/trace.go:171","msg":"trace[1559509337] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"2.737851813s","start":"2026-02-22T05:03:25.259328Z","end":"2026-02-22T05:03:27.997179Z","steps":["trace[1559509337] 'process raft request' (duration: 2.737634237s)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:27.997432Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:25.25926Z","time spent":"2.73807541s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:03:27.997559Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:25.259321Z","time spent":"2.738123919s","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:03:28.011689Z","caller":"traceutil/trace.go:171","msg":"trace[1296937705] linearizableReadLoop","detail":"{readStateIndex:593; appliedIndex:589; }","duration":"2.157028043s","start":"2026-02-22T05:03:25.854634Z","end":"2026-02-22T05:03:28.011662Z","steps":["trace[1296937705] 'read index received' (duration: 1.758409406s)","trace[1296937705] 'applied index is now lower than readState.Index' (duration: 398.617277ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:03:28.011904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"876.399859ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:03:28.011939Z","caller":"traceutil/trace.go:171","msg":"trace[187448979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"876.484108ms","start":"2026-02-22T05:03:27.135444Z","end":"2026-02-22T05:03:28.011928Z","steps":["trace[187448979] 'agreement among raft nodes before linearized reading' (duration: 876.401589ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:28.011972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.40547ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T05:03:28.011974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:27.13543Z","time spent":"876.536447ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:03:28.012032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"754.317973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-22T05:03:28.012041Z","caller":"traceutil/trace.go:171","msg":"trace[1219976782] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:570; }","duration":"157.510789ms","start":"2026-02-22T05:03:27.854511Z","end":"2026-02-22T05:03:28.012022Z","steps":["trace[1219976782] 'agreement among raft nodes before linearized reading' (duration: 157.39986ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:28.012086Z","caller":"traceutil/trace.go:171","msg":"trace[719753984] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:570; }","duration":"754.405232ms","start":"2026-02-22T05:03:27.257664Z","end":"2026-02-22T05:03:28.01207Z","steps":["trace[719753984] 'agreement among raft nodes before linearized reading' (duration: 754.250974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:28.012119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:27.257626Z","time spent":"754.48484ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-22T05:03:30.68049Z","caller":"traceutil/trace.go:171","msg":"trace[1932368975] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"464.320315ms","start":"2026-02-22T05:03:30.216112Z","end":"2026-02-22T05:03:30.680433Z","steps":["trace[1932368975] 'process raft request' (duration: 464.114488ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:30.680807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:03:30.216075Z","time spent":"464.512363ms","remote":"127.0.0.1:38448","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4234,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-22T05:03:30.68981Z","caller":"traceutil/trace.go:171","msg":"trace[712479897] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"271.924276ms","start":"2026-02-22T05:03:30.417859Z","end":"2026-02-22T05:03:30.689784Z","steps":["trace[712479897] 'process raft request' (duration: 271.761987ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:30.689953Z","caller":"traceutil/trace.go:171","msg":"trace[456491516] transaction","detail":"{read_only:false; response_revision:611; number_of_response:1; }","duration":"232.43365ms","start":"2026-02-22T05:03:30.457478Z","end":"2026-02-22T05:03:30.689911Z","steps":["trace[456491516] 'process raft request' (duration: 232.238432ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:33.369179Z","caller":"traceutil/trace.go:171","msg":"trace[671075535] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"272.580588ms","start":"2026-02-22T05:03:33.096567Z","end":"2026-02-22T05:03:33.369148Z","steps":["trace[671075535] 'process raft request' (duration: 270.179166ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:03:33.369355Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.851204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-02-22T05:03:33.369418Z","caller":"traceutil/trace.go:171","msg":"trace[1030442170] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:644; }","duration":"154.954623ms","start":"2026-02-22T05:03:33.214439Z","end":"2026-02-22T05:03:33.369393Z","steps":["trace[1030442170] 'agreement among raft nodes before linearized reading' (duration: 154.762674ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:33.368941Z","caller":"traceutil/trace.go:171","msg":"trace[1852711286] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"154.439438ms","start":"2026-02-22T05:03:33.214483Z","end":"2026-02-22T05:03:33.368923Z","steps":["trace[1852711286] 'read index received' (duration: 152.045865ms)","trace[1852711286] 'applied index is now lower than readState.Index' (duration: 2.392823ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:03:48.294495Z","caller":"traceutil/trace.go:171","msg":"trace[1576714628] transaction","detail":"{read_only:false; response_revision:772; number_of_response:1; }","duration":"193.39429ms","start":"2026-02-22T05:03:48.10108Z","end":"2026-02-22T05:03:48.294474Z","steps":["trace[1576714628] 'process raft request' (duration: 193.273059ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:48.296062Z","caller":"traceutil/trace.go:171","msg":"trace[632093334] transaction","detail":"{read_only:false; response_revision:774; number_of_response:1; }","duration":"193.906574ms","start":"2026-02-22T05:03:48.102138Z","end":"2026-02-22T05:03:48.296045Z","steps":["trace[632093334] 'process raft request' (duration: 193.854434ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:03:48.296204Z","caller":"traceutil/trace.go:171","msg":"trace[1323169644] transaction","detail":"{read_only:false; response_revision:773; number_of_response:1; }","duration":"194.820162ms","start":"2026-02-22T05:03:48.101277Z","end":"2026-02-22T05:03:48.296097Z","steps":["trace[1323169644] 'process raft request' (duration: 194.641231ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:04:44.298231Z","caller":"traceutil/trace.go:171","msg":"trace[283575886] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1008; }","duration":"111.14695ms","start":"2026-02-22T05:04:44.18706Z","end":"2026-02-22T05:04:44.298207Z","steps":["trace[283575886] 'read index received' (duration: 111.019044ms)","trace[283575886] 'applied index is now lower than readState.Index' (duration: 126.846µs)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:04:44.298359Z","caller":"traceutil/trace.go:171","msg":"trace[1995361693] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"145.472419ms","start":"2026-02-22T05:04:44.152851Z","end":"2026-02-22T05:04:44.298323Z","steps":["trace[1995361693] 'process raft request' (duration: 145.217088ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:44.298428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.358329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-22T05:04:44.298471Z","caller":"traceutil/trace.go:171","msg":"trace[11814509] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"111.459304ms","start":"2026-02-22T05:04:44.187002Z","end":"2026-02-22T05:04:44.298461Z","steps":["trace[11814509] 'agreement among raft nodes before linearized reading' (duration: 111.304547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:52.673127Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.520915ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-22T05:04:52.673287Z","caller":"traceutil/trace.go:171","msg":"trace[1628539246] linearizableReadLoop","detail":"{readStateIndex:1040; appliedIndex:1038; }","duration":"296.584416ms","start":"2026-02-22T05:04:52.376677Z","end":"2026-02-22T05:04:52.673261Z","steps":["trace[1628539246] 'read index received' (duration: 46.15716ms)","trace[1628539246] 'applied index is now lower than readState.Index' (duration: 250.424866ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:04:52.67331Z","caller":"traceutil/trace.go:171","msg":"trace[1678052976] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"298.103807ms","start":"2026-02-22T05:04:52.375157Z","end":"2026-02-22T05:04:52.673261Z","steps":["trace[1678052976] 'process raft request' (duration: 171.277968ms)","trace[1678052976] 'compare' (duration: 125.917527ms)"],"step_count":2} {"level":"warn","ts":"2026-02-22T05:04:52.673534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.831968ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-22T05:04:52.673624Z","caller":"traceutil/trace.go:171","msg":"trace[1865235034] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:995; }","duration":"296.973635ms","start":"2026-02-22T05:04:52.376632Z","end":"2026-02-22T05:04:52.673606Z","steps":["trace[1865235034] 'agreement among raft nodes before linearized reading' (duration: 296.722843ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:04:53.329153Z","caller":"traceutil/trace.go:171","msg":"trace[1324773151] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1040; }","duration":"618.407416ms","start":"2026-02-22T05:04:52.71072Z","end":"2026-02-22T05:04:53.329127Z","steps":["trace[1324773151] 'read index received' (duration: 59.383034ms)","trace[1324773151] 'applied index is now lower than readState.Index' (duration: 559.019892ms)"],"step_count":2} {"level":"info","ts":"2026-02-22T05:04:53.329308Z","caller":"traceutil/trace.go:171","msg":"trace[811589577] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"649.58776ms","start":"2026-02-22T05:04:52.679709Z","end":"2026-02-22T05:04:53.329296Z","steps":["trace[811589577] 'process raft request' (duration: 649.038353ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:53.329405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"618.669528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-22T05:04:53.329433Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:04:52.679693Z","time spent":"649.653252ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:04:53.329432Z","caller":"traceutil/trace.go:171","msg":"trace[1838611473] transaction","detail":"{read_only:false; response_revision:998; number_of_response:1; }","duration":"649.566078ms","start":"2026-02-22T05:04:52.679827Z","end":"2026-02-22T05:04:53.329393Z","steps":["trace[1838611473] 'process raft request' (duration: 649.177109ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:04:53.329479Z","caller":"traceutil/trace.go:171","msg":"trace[194940699] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:999; }","duration":"618.798725ms","start":"2026-02-22T05:04:52.710664Z","end":"2026-02-22T05:04:53.329462Z","steps":["trace[194940699] 'agreement among raft nodes before linearized reading' (duration: 618.660758ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:53.329532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:04:52.710645Z","time spent":"618.873558ms","remote":"127.0.0.1:38554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":0,"response size":27,"request content":"key:\"/registry/persistentvolumes/test-pv\" "} {"level":"warn","ts":"2026-02-22T05:04:53.329586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:04:52.679818Z","time spent":"649.677993ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-22T05:04:53.329655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"474.622997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-22T05:04:53.329775Z","caller":"traceutil/trace.go:171","msg":"trace[1040206165] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:999; }","duration":"474.751853ms","start":"2026-02-22T05:04:52.854992Z","end":"2026-02-22T05:04:53.329743Z","steps":["trace[1040206165] 'agreement among raft nodes before linearized reading' (duration: 474.572174ms)"],"step_count":1} {"level":"info","ts":"2026-02-22T05:04:53.329696Z","caller":"traceutil/trace.go:171","msg":"trace[25369102] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"649.645942ms","start":"2026-02-22T05:04:52.679978Z","end":"2026-02-22T05:04:53.329624Z","steps":["trace[25369102] 'process raft request' (duration: 649.073004ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:53.32984Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:04:52.8549Z","time spent":"474.931372ms","remote":"127.0.0.1:38410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-22T05:04:53.329742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.974846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-22T05:04:53.330001Z","caller":"traceutil/trace.go:171","msg":"trace[1033654516] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:999; }","duration":"252.248579ms","start":"2026-02-22T05:04:53.077663Z","end":"2026-02-22T05:04:53.329911Z","steps":["trace[1033654516] 'agreement among raft nodes before linearized reading' (duration: 251.929674ms)"],"step_count":1} {"level":"warn","ts":"2026-02-22T05:04:53.330115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-22T05:04:52.679948Z","time spent":"649.930395ms","remote":"127.0.0.1:38668","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-22T05:04:56.905207Z","caller":"traceutil/trace.go:171","msg":"trace[1048589061] transaction","detail":"{read_only:false; response_revision:1011; number_of_response:1; }","duration":"108.228757ms","start":"2026-02-22T05:04:56.796959Z","end":"2026-02-22T05:04:56.905188Z","steps":["trace[1048589061] 'process raft request' (duration: 106.278832ms)"],"step_count":1}