{"level":"warn","ts":"2026-01-23T00:53:58.04873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-23T00:53:58.049023Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.207: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.207:2380","--initial-cluster=instance=https://162.253.55.207:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.207:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.207: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-01-23T00:53:58.049147Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-01-23T00:53:58.049186Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-01-23T00:53:58.049239Z","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-01-23T00:53:58.049913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"]} {"level":"info","ts":"2026-01-23T00:53:58.053186Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.207: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-01-23T00:53:58.058992Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.44918ms"} {"level":"info","ts":"2026-01-23T00:53:58.067795Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"6f1fcbe50562a63a","cluster-id":"a9b9f04f4e8a9fba"} {"level":"info","ts":"2026-01-23T00:53:58.067882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=()"} {"level":"info","ts":"2026-01-23T00:53:58.067929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 0"} {"level":"info","ts":"2026-01-23T00:53:58.067962Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6f1fcbe50562a63a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-01-23T00:53:58.068058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became follower at term 1"} {"level":"info","ts":"2026-01-23T00:53:58.068162Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"warn","ts":"2026-01-23T00:53:58.078234Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-01-23T00:53:58.086259Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-01-23T00:53:58.087563Z","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-01-23T00:53:58.089701Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"6f1fcbe50562a63a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-01-23T00:53:58.09249Z","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-01-23T00:53:58.093275Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"6f1fcbe50562a63a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-01-23T00:53:58.093452Z","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-01-23T00:53:58.093476Z","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-01-23T00:53:58.093483Z","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-01-23T00:53:58.093742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a switched to configuration voters=(8007342846986331706)"} {"level":"info","ts":"2026-01-23T00:53:58.093836Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"6f1fcbe50562a63a","initial-advertise-peer-urls":["https://162.253.55.207:2380"],"listen-peer-urls":["https://162.253.55.207:2380"],"advertise-client-urls":["https://162.253.55.207:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.207:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-01-23T00:53:58.093988Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-01-23T00:53:58.094215Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-01-23T00:53:58.094285Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.207:2380"} {"level":"info","ts":"2026-01-23T00:53:58.094425Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","added-peer-id":"6f1fcbe50562a63a","added-peer-peer-urls":["https://162.253.55.207:2380"]} {"level":"info","ts":"2026-01-23T00:53:58.170324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a is starting a new election at term 1"} {"level":"info","ts":"2026-01-23T00:53:58.170361Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became pre-candidate at term 1"} {"level":"info","ts":"2026-01-23T00:53:58.170384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgPreVoteResp from 6f1fcbe50562a63a at term 1"} {"level":"info","ts":"2026-01-23T00:53:58.170469Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became candidate at term 2"} {"level":"info","ts":"2026-01-23T00:53:58.17048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a received MsgVoteResp from 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-01-23T00:53:58.170489Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6f1fcbe50562a63a became leader at term 2"} {"level":"info","ts":"2026-01-23T00:53:58.170533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6f1fcbe50562a63a elected leader 6f1fcbe50562a63a at term 2"} {"level":"info","ts":"2026-01-23T00:53:58.174123Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"6f1fcbe50562a63a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.207:2379]}","request-path":"/0/members/6f1fcbe50562a63a/attributes","cluster-id":"a9b9f04f4e8a9fba","publish-timeout":"7s"} {"level":"info","ts":"2026-01-23T00:53:58.174237Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-23T00:53:58.174323Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-01-23T00:53:58.174625Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-01-23T00:53:58.174669Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-01-23T00:53:58.175968Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-01-23T00:53:58.175961Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-01-23T00:53:58.177436Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.207:2379"} {"level":"info","ts":"2026-01-23T00:53:58.195467Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"a9b9f04f4e8a9fba","local-member-id":"6f1fcbe50562a63a","cluster-version":"3.5"} {"level":"info","ts":"2026-01-23T00:53:58.196372Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-01-23T00:53:58.196485Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-01-23T00:54:14.129212Z","caller":"traceutil/trace.go:171","msg":"trace[1956338838] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"932.758892ms","start":"2026-01-23T00:54:13.196436Z","end":"2026-01-23T00:54:14.129195Z","steps":["trace[1956338838] 'process raft request' (duration: 932.66705ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:14.129667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.196421Z","time spent":"932.856904ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5255,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:15.352193Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.223071393s","expected-duration":"1s"} {"level":"info","ts":"2026-01-23T00:54:15.352691Z","caller":"traceutil/trace.go:171","msg":"trace[560888375] linearizableReadLoop","detail":"{readStateIndex:418; appliedIndex:416; }","duration":"1.690987561s","start":"2026-01-23T00:54:13.661675Z","end":"2026-01-23T00:54:15.352663Z","steps":["trace[560888375] 'read index received' (duration: 467.972239ms)","trace[560888375] 'applied index is now lower than readState.Index' (duration: 1.223014082s)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:15.352719Z","caller":"traceutil/trace.go:171","msg":"trace[838822042] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"1.931542851s","start":"2026-01-23T00:54:13.421145Z","end":"2026-01-23T00:54:15.352688Z","steps":["trace[838822042] 'process raft request' (duration: 1.931339256s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.353034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.421123Z","time spent":"1.931751776s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:15.35323Z","caller":"traceutil/trace.go:171","msg":"trace[1185310458] transaction","detail":"{read_only:false; response_revision:408; number_of_response:1; }","duration":"1.771686186s","start":"2026-01-23T00:54:13.581528Z","end":"2026-01-23T00:54:15.353214Z","steps":["trace[1185310458] 'process raft request' (duration: 1.771087252s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.353688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.581508Z","time spent":"1.771881171s","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4042,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:15.353895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.475447654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:15.354611Z","caller":"traceutil/trace.go:171","msg":"trace[619652307] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:408; }","duration":"1.476200632s","start":"2026-01-23T00:54:13.8784Z","end":"2026-01-23T00:54:15.354601Z","steps":["trace[619652307] 'agreement among raft nodes before linearized reading' (duration: 1.475468015s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.354711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.878386Z","time spent":"1.476318515s","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-23T00:54:15.35394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692263391s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-01-23T00:54:15.35497Z","caller":"traceutil/trace.go:171","msg":"trace[1623267247] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:408; }","duration":"1.693308995s","start":"2026-01-23T00:54:13.661645Z","end":"2026-01-23T00:54:15.354954Z","steps":["trace[1623267247] 'agreement among raft nodes before linearized reading' (duration: 1.692275581s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.354992Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.661629Z","time spent":"1.693354547s","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4079,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-01-23T00:54:15.353962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"933.942821ms","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-01-23T00:54:15.35676Z","caller":"traceutil/trace.go:171","msg":"trace[1453864673] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:408; }","duration":"936.746787ms","start":"2026-01-23T00:54:14.419996Z","end":"2026-01-23T00:54:15.356743Z","steps":["trace[1453864673] 'agreement among raft nodes before linearized reading' (duration: 933.951201ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.356959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:14.419985Z","time spent":"936.88338ms","remote":"127.0.0.1:55592","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-01-23T00:54:15.353982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"935.428406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:54:15.358077Z","caller":"traceutil/trace.go:171","msg":"trace[416692377] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:408; }","duration":"939.546744ms","start":"2026-01-23T00:54:14.418515Z","end":"2026-01-23T00:54:15.358062Z","steps":["trace[416692377] 'agreement among raft nodes before linearized reading' (duration: 935.455557ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.358206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:14.418495Z","time spent":"939.693557ms","remote":"127.0.0.1:55592","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-01-23T00:54:15.354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.087630839s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-01-23T00:54:15.354143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358099738s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-01-23T00:54:15.354186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.219953389s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5270"} {"level":"info","ts":"2026-01-23T00:54:15.358769Z","caller":"traceutil/trace.go:171","msg":"trace[442700132] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:408; }","duration":"1.092379622s","start":"2026-01-23T00:54:14.266329Z","end":"2026-01-23T00:54:15.358709Z","steps":["trace[442700132] 'agreement among raft nodes before linearized reading' (duration: 1.08765936s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.358843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:14.266308Z","time spent":"1.092486825s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-23T00:54:15.359047Z","caller":"traceutil/trace.go:171","msg":"trace[1308769655] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:408; }","duration":"1.363039505s","start":"2026-01-23T00:54:13.996001Z","end":"2026-01-23T00:54:15.359041Z","steps":["trace[1308769655] 'agreement among raft nodes before linearized reading' (duration: 1.358120749s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.359074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:13.995983Z","time spent":"1.363081727s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-01-23T00:54:15.359448Z","caller":"traceutil/trace.go:171","msg":"trace[1276544343] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:408; }","duration":"1.225223844s","start":"2026-01-23T00:54:14.134207Z","end":"2026-01-23T00:54:15.35943Z","steps":["trace[1276544343] 'agreement among raft nodes before linearized reading' (duration: 1.219799635s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:15.359846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:14.134194Z","time spent":"1.225634244s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5293,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-01-23T00:54:30.882022Z","caller":"traceutil/trace.go:171","msg":"trace[1337315584] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"738.844239ms","start":"2026-01-23T00:54:30.143164Z","end":"2026-01-23T00:54:30.882008Z","steps":["trace[1337315584] 'process raft request' (duration: 738.621174ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:30.882128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:30.143154Z","time spent":"738.929561ms","remote":"127.0.0.1:55532","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4254,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:31.254961Z","caller":"traceutil/trace.go:171","msg":"trace[135996388] linearizableReadLoop","detail":"{readStateIndex:528; appliedIndex:527; }","duration":"377.753837ms","start":"2026-01-23T00:54:30.877191Z","end":"2026-01-23T00:54:31.254945Z","steps":["trace[135996388] 'read index received' (duration: 4.679331ms)","trace[135996388] 'applied index is now lower than readState.Index' (duration: 373.073716ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:54:31.254932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.458542ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:31.255009Z","caller":"traceutil/trace.go:171","msg":"trace[634826891] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:513; }","duration":"134.548135ms","start":"2026-01-23T00:54:31.120456Z","end":"2026-01-23T00:54:31.255004Z","steps":["trace[634826891] 'range keys from in-memory index tree' (duration: 134.448981ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:31.255085Z","caller":"traceutil/trace.go:171","msg":"trace[1216621975] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"645.139754ms","start":"2026-01-23T00:54:30.609938Z","end":"2026-01-23T00:54:31.255077Z","steps":["trace[1216621975] 'process raft request' (duration: 643.301571ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:31.255137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.942482ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-01-23T00:54:31.255145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:30.609913Z","time spent":"645.192756ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:31.255158Z","caller":"traceutil/trace.go:171","msg":"trace[1946581586] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:514; }","duration":"377.997923ms","start":"2026-01-23T00:54:30.877152Z","end":"2026-01-23T00:54:31.25515Z","steps":["trace[1946581586] 'agreement among raft nodes before linearized reading' (duration: 377.963962ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:31.255177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:30.877136Z","time spent":"378.037234ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-23T00:54:33.648Z","caller":"traceutil/trace.go:171","msg":"trace[1080413068] linearizableReadLoop","detail":"{readStateIndex:535; appliedIndex:534; }","duration":"130.275183ms","start":"2026-01-23T00:54:33.517709Z","end":"2026-01-23T00:54:33.647984Z","steps":["trace[1080413068] 'read index received' (duration: 130.15249ms)","trace[1080413068] 'applied index is now lower than readState.Index' (duration: 120.413µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:33.648193Z","caller":"traceutil/trace.go:171","msg":"trace[1151318561] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"175.882126ms","start":"2026-01-23T00:54:33.472304Z","end":"2026-01-23T00:54:33.648186Z","steps":["trace[1151318561] 'process raft request' (duration: 175.595389ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:33.648328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.650292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-23T00:54:33.648355Z","caller":"traceutil/trace.go:171","msg":"trace[1015223005] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:520; }","duration":"130.717434ms","start":"2026-01-23T00:54:33.517632Z","end":"2026-01-23T00:54:33.64835Z","steps":["trace[1015223005] 'agreement among raft nodes before linearized reading' (duration: 130.663302ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:33.648425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.553013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:54:33.648441Z","caller":"traceutil/trace.go:171","msg":"trace[291433342] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:520; }","duration":"123.589674ms","start":"2026-01-23T00:54:33.524848Z","end":"2026-01-23T00:54:33.648438Z","steps":["trace[291433342] 'agreement among raft nodes before linearized reading' (duration: 123.563093ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:33.648532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.295656ms","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-01-23T00:54:33.648548Z","caller":"traceutil/trace.go:171","msg":"trace[1225553200] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:520; }","duration":"120.325796ms","start":"2026-01-23T00:54:33.528219Z","end":"2026-01-23T00:54:33.648544Z","steps":["trace[1225553200] 'agreement among raft nodes before linearized reading' (duration: 120.295575ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:33.854708Z","caller":"traceutil/trace.go:171","msg":"trace[58332181] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"200.156182ms","start":"2026-01-23T00:54:33.654535Z","end":"2026-01-23T00:54:33.854692Z","steps":["trace[58332181] 'process raft request' (duration: 200.131771ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:33.854994Z","caller":"traceutil/trace.go:171","msg":"trace[231081821] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"201.891873ms","start":"2026-01-23T00:54:33.653096Z","end":"2026-01-23T00:54:33.854987Z","steps":["trace[231081821] 'process raft request' (duration: 201.487253ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:40.066552Z","caller":"traceutil/trace.go:171","msg":"trace[910988071] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"376.043086ms","start":"2026-01-23T00:54:39.690494Z","end":"2026-01-23T00:54:40.066537Z","steps":["trace[910988071] 'read index received' (duration: 375.907653ms)","trace[910988071] 'applied index is now lower than readState.Index' (duration: 134.933µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:40.066725Z","caller":"traceutil/trace.go:171","msg":"trace[1209766118] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"952.06473ms","start":"2026-01-23T00:54:39.114654Z","end":"2026-01-23T00:54:40.066719Z","steps":["trace[1209766118] 'process raft request' (duration: 951.790783ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:40.066785Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:39.11463Z","time spent":"952.11239ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16803,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:40.066916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.421025ms","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-01-23T00:54:40.066934Z","caller":"traceutil/trace.go:171","msg":"trace[942167288] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:536; }","duration":"376.475207ms","start":"2026-01-23T00:54:39.690454Z","end":"2026-01-23T00:54:40.066929Z","steps":["trace[942167288] 'agreement among raft nodes before linearized reading' (duration: 376.411345ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:40.066949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:39.690439Z","time spent":"376.505007ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-23T00:54:40.067048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.657082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-23T00:54:40.067061Z","caller":"traceutil/trace.go:171","msg":"trace[1491256642] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:536; }","duration":"371.692694ms","start":"2026-01-23T00:54:39.695364Z","end":"2026-01-23T00:54:40.067057Z","steps":["trace[1491256642] 'agreement among raft nodes before linearized reading' (duration: 371.655433ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:40.067086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:39.695354Z","time spent":"371.729474ms","remote":"127.0.0.1:55592","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-01-23T00:54:40.067226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.293174ms","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-01-23T00:54:40.067238Z","caller":"traceutil/trace.go:171","msg":"trace[702473374] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:536; }","duration":"181.306534ms","start":"2026-01-23T00:54:39.885928Z","end":"2026-01-23T00:54:40.067235Z","steps":["trace[702473374] 'agreement among raft nodes before linearized reading' (duration: 181.268613ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:40.06731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.492386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:54:40.067321Z","caller":"traceutil/trace.go:171","msg":"trace[200782433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:536; }","duration":"183.504106ms","start":"2026-01-23T00:54:39.883814Z","end":"2026-01-23T00:54:40.067318Z","steps":["trace[200782433] 'agreement among raft nodes before linearized reading' (duration: 183.469185ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:41.420029Z","caller":"traceutil/trace.go:171","msg":"trace[1292901947] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"215.245859ms","start":"2026-01-23T00:54:41.204763Z","end":"2026-01-23T00:54:41.420009Z","steps":["trace[1292901947] 'process raft request' (duration: 213.535029ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:41.422457Z","caller":"traceutil/trace.go:171","msg":"trace[769844899] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"113.73356ms","start":"2026-01-23T00:54:41.308697Z","end":"2026-01-23T00:54:41.422431Z","steps":["trace[769844899] 'process raft request' (duration: 113.648618ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:44.440777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.733816ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:54:44.440996Z","caller":"traceutil/trace.go:171","msg":"trace[1373540027] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"191.502816ms","start":"2026-01-23T00:54:44.249458Z","end":"2026-01-23T00:54:44.44096Z","steps":["trace[1373540027] 'process raft request' (duration: 62.344811ms)","trace[1373540027] 'compare' (duration: 128.612142ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:52.526325Z","caller":"traceutil/trace.go:171","msg":"trace[2056601230] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"278.284625ms","start":"2026-01-23T00:54:52.248025Z","end":"2026-01-23T00:54:52.52631Z","steps":["trace[2056601230] 'process raft request' (duration: 278.216164ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:52.526525Z","caller":"traceutil/trace.go:171","msg":"trace[2046994458] transaction","detail":"{read_only:false; response_revision:682; number_of_response:1; }","duration":"283.883329ms","start":"2026-01-23T00:54:52.242636Z","end":"2026-01-23T00:54:52.526519Z","steps":["trace[2046994458] 'process raft request' (duration: 205.375666ms)","trace[2046994458] 'compare' (duration: 78.101714ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:53.041109Z","caller":"traceutil/trace.go:171","msg":"trace[368535982] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"774.436984ms","start":"2026-01-23T00:54:52.266645Z","end":"2026-01-23T00:54:53.041082Z","steps":["trace[368535982] 'process raft request' (duration: 716.026298ms)","trace[368535982] 'compare' (duration: 58.257083ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:54:53.041246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:52.26663Z","time spent":"774.544287ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4794,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:53.118592Z","caller":"traceutil/trace.go:171","msg":"trace[1401234648] linearizableReadLoop","detail":"{readStateIndex:705; appliedIndex:703; }","duration":"242.849405ms","start":"2026-01-23T00:54:52.87572Z","end":"2026-01-23T00:54:53.118569Z","steps":["trace[1401234648] 'read index received' (duration: 106.963309ms)","trace[1401234648] 'applied index is now lower than readState.Index' (duration: 135.885386ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:53.118859Z","caller":"traceutil/trace.go:171","msg":"trace[727426252] transaction","detail":"{read_only:false; response_revision:685; number_of_response:1; }","duration":"586.645987ms","start":"2026-01-23T00:54:52.532204Z","end":"2026-01-23T00:54:53.11885Z","steps":["trace[727426252] 'process raft request' (duration: 586.239117ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:53.118945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:52.532168Z","time spent":"586.713427ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3782,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:53.119097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.379607ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:53.11912Z","caller":"traceutil/trace.go:171","msg":"trace[385205492] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:685; }","duration":"243.427039ms","start":"2026-01-23T00:54:52.875686Z","end":"2026-01-23T00:54:53.119113Z","steps":["trace[385205492] 'agreement among raft nodes before linearized reading' (duration: 243.387848ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:53.619393Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057581155891630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-23T00:54:54.120357Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057581155891630,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-01-23T00:54:54.375927Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.25749807s","expected-duration":"1s"} {"level":"info","ts":"2026-01-23T00:54:54.376313Z","caller":"traceutil/trace.go:171","msg":"trace[818701011] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"1.324287236s","start":"2026-01-23T00:54:53.05201Z","end":"2026-01-23T00:54:54.376298Z","steps":["trace[818701011] 'process raft request' (duration: 1.324249945s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.376389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.051906Z","time spent":"1.324434959s","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:54.376598Z","caller":"traceutil/trace.go:171","msg":"trace[1933935291] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"1.324664955s","start":"2026-01-23T00:54:53.051927Z","end":"2026-01-23T00:54:54.376592Z","steps":["trace[1933935291] 'process raft request' (duration: 1.324155873s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.376632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.051905Z","time spent":"1.324708395s","remote":"127.0.0.1:55520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":900,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:54.376716Z","caller":"traceutil/trace.go:171","msg":"trace[328966339] transaction","detail":"{read_only:false; number_of_response:1; response_revision:686; }","duration":"1.324739107s","start":"2026-01-23T00:54:53.05197Z","end":"2026-01-23T00:54:54.376709Z","steps":["trace[328966339] 'process raft request' (duration: 1.324253605s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.376746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.051954Z","time spent":"1.324775837s","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":56,"response count":0,"response size":3831,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:54.85318Z","caller":"traceutil/trace.go:171","msg":"trace[2083914614] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:708; }","duration":"1.734517204s","start":"2026-01-23T00:54:53.118645Z","end":"2026-01-23T00:54:54.853163Z","steps":["trace[2083914614] 'read index received' (duration: 1.258433133s)","trace[2083914614] 'applied index is now lower than readState.Index' (duration: 476.083441ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:54.853282Z","caller":"traceutil/trace.go:171","msg":"trace[798150340] transaction","detail":"{read_only:false; response_revision:688; number_of_response:1; }","duration":"1.73053078s","start":"2026-01-23T00:54:53.122745Z","end":"2026-01-23T00:54:54.853276Z","steps":["trace[798150340] 'process raft request' (duration: 1.721052585s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.853344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.122727Z","time spent":"1.730571951s","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":689,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-01-23T00:54:54.853606Z","caller":"traceutil/trace.go:171","msg":"trace[97127199] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"1.729913676s","start":"2026-01-23T00:54:53.123685Z","end":"2026-01-23T00:54:54.853599Z","steps":["trace[97127199] 'process raft request' (duration: 1.729435814s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.853653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.123677Z","time spent":"1.729947236s","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:54.853819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.801803211s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-b75cq\" ","response":"range_response_count:1 size:4733"} {"level":"info","ts":"2026-01-23T00:54:54.853841Z","caller":"traceutil/trace.go:171","msg":"trace[837024998] range","detail":"{range_begin:/registry/pods/kube-system/coredns-77cccfdc44-b75cq; range_end:; response_count:1; response_revision:689; }","duration":"1.801857533s","start":"2026-01-23T00:54:53.051977Z","end":"2026-01-23T00:54:54.853835Z","steps":["trace[837024998] 'agreement among raft nodes before linearized reading' (duration: 1.801756311s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.85386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.05197Z","time spent":"1.801885494s","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4756,"request content":"key:\"/registry/pods/kube-system/coredns-77cccfdc44-b75cq\" "} {"level":"warn","ts":"2026-01-23T00:54:54.853986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.731166575s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:54.854004Z","caller":"traceutil/trace.go:171","msg":"trace[378373600] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:689; }","duration":"1.731203096s","start":"2026-01-23T00:54:53.122795Z","end":"2026-01-23T00:54:54.853998Z","steps":["trace[378373600] 'agreement among raft nodes before linearized reading' (duration: 1.731176046s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:53.122789Z","time spent":"1.731231677s","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-01-23T00:54:54.854293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"472.078817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" ","response":"range_response_count:1 size:3797"} {"level":"info","ts":"2026-01-23T00:54:54.854314Z","caller":"traceutil/trace.go:171","msg":"trace[970760779] range","detail":"{range_begin:/registry/replicasets/kube-system/coredns-5dd5756b68; range_end:; response_count:1; response_revision:689; }","duration":"472.118878ms","start":"2026-01-23T00:54:54.38219Z","end":"2026-01-23T00:54:54.854308Z","steps":["trace[970760779] 'agreement among raft nodes before linearized reading' (duration: 472.013495ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.382179Z","time spent":"472.147858ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":3820,"request content":"key:\"/registry/replicasets/kube-system/coredns-5dd5756b68\" "} {"level":"warn","ts":"2026-01-23T00:54:54.854448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.380929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-23T00:54:54.854464Z","caller":"traceutil/trace.go:171","msg":"trace[1185647197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:689; }","duration":"682.42021ms","start":"2026-01-23T00:54:54.172039Z","end":"2026-01-23T00:54:54.854459Z","steps":["trace[1185647197] 'agreement among raft nodes before linearized reading' (duration: 682.368899ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.172036Z","time spent":"682.438871ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-23T00:54:54.854644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"682.609014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-23T00:54:54.854661Z","caller":"traceutil/trace.go:171","msg":"trace[897756882] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:689; }","duration":"682.635365ms","start":"2026-01-23T00:54:54.17202Z","end":"2026-01-23T00:54:54.854656Z","steps":["trace[897756882] 'agreement among raft nodes before linearized reading' (duration: 682.588534ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.172016Z","time spent":"682.657365ms","remote":"127.0.0.1:55592","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":"warn","ts":"2026-01-23T00:54:54.854798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.47098ms","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-01-23T00:54:54.854815Z","caller":"traceutil/trace.go:171","msg":"trace[2007796708] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:689; }","duration":"687.493261ms","start":"2026-01-23T00:54:54.167317Z","end":"2026-01-23T00:54:54.85481Z","steps":["trace[2007796708] 'agreement among raft nodes before linearized reading' (duration: 687.431659ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.167315Z","time spent":"687.512481ms","remote":"127.0.0.1:55592","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-01-23T00:54:54.854935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.624153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-23T00:54:54.854951Z","caller":"traceutil/trace.go:171","msg":"trace[1110621792] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:689; }","duration":"687.656594ms","start":"2026-01-23T00:54:54.16729Z","end":"2026-01-23T00:54:54.854946Z","steps":["trace[1110621792] 'agreement among raft nodes before linearized reading' (duration: 687.613543ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.854966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.167279Z","time spent":"687.683815ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-23T00:54:54.855079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"768.873212ms","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-01-23T00:54:54.855096Z","caller":"traceutil/trace.go:171","msg":"trace[237561216] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:689; }","duration":"768.910053ms","start":"2026-01-23T00:54:54.086181Z","end":"2026-01-23T00:54:54.855091Z","steps":["trace[237561216] 'agreement among raft nodes before linearized reading' (duration: 768.860212ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:54.855112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.086167Z","time spent":"768.941384ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-23T00:54:55.191384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.428204ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:54:55.19148Z","caller":"traceutil/trace.go:171","msg":"trace[1343321171] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"322.621179ms","start":"2026-01-23T00:54:54.868841Z","end":"2026-01-23T00:54:55.191462Z","steps":["trace[1343321171] 'process raft request' (duration: 99.034831ms)","trace[1343321171] 'compare' (duration: 223.283471ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:54:55.19155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.868826Z","time spent":"322.69837ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194241Z","caller":"traceutil/trace.go:171","msg":"trace[1623262232] linearizableReadLoop","detail":"{readStateIndex:718; appliedIndex:710; }","duration":"315.463468ms","start":"2026-01-23T00:54:54.878763Z","end":"2026-01-23T00:54:55.194226Z","steps":["trace[1623262232] 'read index received' (duration: 89.122175ms)","trace[1623262232] 'applied index is now lower than readState.Index' (duration: 226.340713ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:54:55.194348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.566941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:55.194365Z","caller":"traceutil/trace.go:171","msg":"trace[931481387] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:697; }","duration":"315.615013ms","start":"2026-01-23T00:54:54.878745Z","end":"2026-01-23T00:54:55.19436Z","steps":["trace[931481387] 'agreement among raft nodes before linearized reading' (duration: 315.558491ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.878731Z","time spent":"315.648433ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-23T00:54:55.194383Z","caller":"traceutil/trace.go:171","msg":"trace[2111731896] transaction","detail":"{read_only:false; response_revision:693; number_of_response:1; }","duration":"317.460696ms","start":"2026-01-23T00:54:54.876913Z","end":"2026-01-23T00:54:55.194374Z","steps":["trace[2111731896] 'process raft request' (duration: 317.21636ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.876909Z","time spent":"317.511177ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194524Z","caller":"traceutil/trace.go:171","msg":"trace[2123981102] transaction","detail":"{read_only:false; response_revision:694; number_of_response:1; }","duration":"317.574948ms","start":"2026-01-23T00:54:54.876943Z","end":"2026-01-23T00:54:55.194518Z","steps":["trace[2123981102] 'process raft request' (duration: 317.20581ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:54:55.194567Z","caller":"traceutil/trace.go:171","msg":"trace[101417824] transaction","detail":"{read_only:false; response_revision:691; number_of_response:1; }","duration":"325.614889ms","start":"2026-01-23T00:54:54.868945Z","end":"2026-01-23T00:54:55.19456Z","steps":["trace[101417824] 'process raft request' (duration: 325.074946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194575Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.87694Z","time spent":"317.59802ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:55.194612Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.868941Z","time spent":"325.64356ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194645Z","caller":"traceutil/trace.go:171","msg":"trace[1423293568] transaction","detail":"{read_only:false; response_revision:695; number_of_response:1; }","duration":"317.677361ms","start":"2026-01-23T00:54:54.876963Z","end":"2026-01-23T00:54:55.19464Z","steps":["trace[1423293568] 'process raft request' (duration: 317.2009ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.87696Z","time spent":"317.695702ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194741Z","caller":"traceutil/trace.go:171","msg":"trace[885107259] transaction","detail":"{read_only:false; response_revision:692; number_of_response:1; }","duration":"317.988419ms","start":"2026-01-23T00:54:54.876747Z","end":"2026-01-23T00:54:55.194735Z","steps":["trace[885107259] 'process raft request' (duration: 317.357553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.19477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.876741Z","time spent":"318.012489ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194794Z","caller":"traceutil/trace.go:171","msg":"trace[1381977813] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"316.802761ms","start":"2026-01-23T00:54:54.877986Z","end":"2026-01-23T00:54:55.194789Z","steps":["trace[1381977813] 'process raft request' (duration: 316.193646ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.87798Z","time spent":"316.824891ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4191,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.194945Z","caller":"traceutil/trace.go:171","msg":"trace[868385655] transaction","detail":"{read_only:false; response_revision:697; number_of_response:1; }","duration":"316.841201ms","start":"2026-01-23T00:54:54.878098Z","end":"2026-01-23T00:54:55.19494Z","steps":["trace[868385655] 'process raft request' (duration: 316.104714ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.194987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:54.878079Z","time spent":"316.893723ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3830,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.546856Z","caller":"traceutil/trace.go:171","msg":"trace[247987491] linearizableReadLoop","detail":"{readStateIndex:719; appliedIndex:718; }","duration":"344.023646ms","start":"2026-01-23T00:54:55.202815Z","end":"2026-01-23T00:54:55.546839Z","steps":["trace[247987491] 'read index received' (duration: 272.364995ms)","trace[247987491] 'applied index is now lower than readState.Index' (duration: 71.658031ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:54:55.546928Z","caller":"traceutil/trace.go:171","msg":"trace[1744405010] transaction","detail":"{read_only:false; response_revision:698; number_of_response:1; }","duration":"344.380995ms","start":"2026-01-23T00:54:55.202538Z","end":"2026-01-23T00:54:55.546919Z","steps":["trace[1744405010] 'process raft request' (duration: 272.633982ms)","trace[1744405010] 'compare' (duration: 71.466947ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:54:55.547009Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.202524Z","time spent":"344.419476ms","remote":"127.0.0.1:55520","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1017,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:55.547098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"344.208691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:54:55.547154Z","caller":"traceutil/trace.go:171","msg":"trace[1399675250] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:698; }","duration":"344.353235ms","start":"2026-01-23T00:54:55.20279Z","end":"2026-01-23T00:54:55.547143Z","steps":["trace[1399675250] 'agreement among raft nodes before linearized reading' (duration: 344.17145ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.547177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.202784Z","time spent":"344.388375ms","remote":"127.0.0.1:55350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-23T00:54:55.629696Z","caller":"traceutil/trace.go:171","msg":"trace[1091468900] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"423.114678ms","start":"2026-01-23T00:54:55.20655Z","end":"2026-01-23T00:54:55.629665Z","steps":["trace[1091468900] 'process raft request' (duration: 423.046836ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.629758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.247481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-2w226\" ","response":"range_response_count:1 size:18250"} {"level":"info","ts":"2026-01-23T00:54:55.629849Z","caller":"traceutil/trace.go:171","msg":"trace[428163411] range","detail":"{range_begin:/registry/pods/kube-system/cilium-2w226; range_end:; response_count:1; response_revision:701; }","duration":"423.361684ms","start":"2026-01-23T00:54:55.206475Z","end":"2026-01-23T00:54:55.629837Z","steps":["trace[428163411] 'agreement among raft nodes before linearized reading' (duration: 423.18041ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.629875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.206469Z","time spent":"423.400855ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18273,"request content":"key:\"/registry/pods/kube-system/cilium-2w226\" "} {"level":"info","ts":"2026-01-23T00:54:55.630007Z","caller":"traceutil/trace.go:171","msg":"trace[2142347079] transaction","detail":"{read_only:false; response_revision:699; number_of_response:1; }","duration":"427.022671ms","start":"2026-01-23T00:54:55.202977Z","end":"2026-01-23T00:54:55.629999Z","steps":["trace[2142347079] 'process raft request' (duration: 426.508509ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.630045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.202971Z","time spent":"427.054182ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1612,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:54:55.630298Z","caller":"traceutil/trace.go:171","msg":"trace[506259568] transaction","detail":"{read_only:false; response_revision:701; number_of_response:1; }","duration":"422.979695ms","start":"2026-01-23T00:54:55.207311Z","end":"2026-01-23T00:54:55.630291Z","steps":["trace[506259568] 'process raft request' (duration: 422.314199ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.63035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.207304Z","time spent":"423.011526ms","remote":"127.0.0.1:55794","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4164,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:55.629817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.206544Z","time spent":"423.21007ms","remote":"127.0.0.1:55824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3866,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:54:55.630501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.990889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fm697\" ","response":"range_response_count:1 size:4809"} {"level":"info","ts":"2026-01-23T00:54:55.630525Z","caller":"traceutil/trace.go:171","msg":"trace[248987067] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-fm697; range_end:; response_count:1; response_revision:701; }","duration":"424.02076ms","start":"2026-01-23T00:54:55.206497Z","end":"2026-01-23T00:54:55.630518Z","steps":["trace[248987067] 'agreement among raft nodes before linearized reading' (duration: 423.931088ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:54:55.630548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:54:55.206495Z","time spent":"424.04626ms","remote":"127.0.0.1:55536","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":4832,"request content":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-fm697\" "} {"level":"info","ts":"2026-01-23T00:55:01.593805Z","caller":"traceutil/trace.go:171","msg":"trace[2040750712] linearizableReadLoop","detail":"{readStateIndex:749; appliedIndex:748; }","duration":"380.077567ms","start":"2026-01-23T00:55:01.213711Z","end":"2026-01-23T00:55:01.593789Z","steps":["trace[2040750712] 'read index received' (duration: 379.965165ms)","trace[2040750712] 'applied index is now lower than readState.Index' (duration: 111.892µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:01.593977Z","caller":"traceutil/trace.go:171","msg":"trace[2117925209] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"382.493807ms","start":"2026-01-23T00:55:01.211478Z","end":"2026-01-23T00:55:01.593972Z","steps":["trace[2117925209] 'process raft request' (duration: 382.23561ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.594038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.211466Z","time spent":"382.525107ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:01.594175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.461867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-23T00:55:01.59419Z","caller":"traceutil/trace.go:171","msg":"trace[1952580955] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:727; }","duration":"380.489688ms","start":"2026-01-23T00:55:01.213696Z","end":"2026-01-23T00:55:01.594185Z","steps":["trace[1952580955] 'agreement among raft nodes before linearized reading' (duration: 380.425596ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.594204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.213689Z","time spent":"380.511768ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-23T00:55:01.594301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.433829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-23T00:55:01.594314Z","caller":"traceutil/trace.go:171","msg":"trace[340150731] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:727; }","duration":"376.45849ms","start":"2026-01-23T00:55:01.217851Z","end":"2026-01-23T00:55:01.59431Z","steps":["trace[340150731] 'agreement among raft nodes before linearized reading' (duration: 376.403369ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.594325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.217845Z","time spent":"376.47832ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-23T00:55:01.594425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.712473ms","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-01-23T00:55:01.59444Z","caller":"traceutil/trace.go:171","msg":"trace[1146325394] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:727; }","duration":"350.740254ms","start":"2026-01-23T00:55:01.243696Z","end":"2026-01-23T00:55:01.594436Z","steps":["trace[1146325394] 'agreement among raft nodes before linearized reading' (duration: 350.702303ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.594451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.243689Z","time spent":"350.759914ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-23T00:55:01.59452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.093544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-23T00:55:01.59453Z","caller":"traceutil/trace.go:171","msg":"trace[369823990] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:727; }","duration":"356.117385ms","start":"2026-01-23T00:55:01.23841Z","end":"2026-01-23T00:55:01.594527Z","steps":["trace[369823990] 'agreement among raft nodes before linearized reading' (duration: 356.085494ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.594541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.238403Z","time spent":"356.135396ms","remote":"127.0.0.1:55592","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":"warn","ts":"2026-01-23T00:55:01.928277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.590817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:55:01.928463Z","caller":"traceutil/trace.go:171","msg":"trace[152610736] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:749; }","duration":"271.869375ms","start":"2026-01-23T00:55:01.656582Z","end":"2026-01-23T00:55:01.928452Z","steps":["trace[152610736] 'read index received' (duration: 95.059133ms)","trace[152610736] 'applied index is now lower than readState.Index' (duration: 176.809602ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:01.928616Z","caller":"traceutil/trace.go:171","msg":"trace[966005367] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"329.567269ms","start":"2026-01-23T00:55:01.599039Z","end":"2026-01-23T00:55:01.928606Z","steps":["trace[966005367] 'process raft request' (duration: 152.592373ms)","trace[966005367] 'compare' (duration: 176.522345ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:01.928656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.599026Z","time spent":"329.60816ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:01.928766Z","caller":"traceutil/trace.go:171","msg":"trace[1107557191] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"329.430446ms","start":"2026-01-23T00:55:01.599329Z","end":"2026-01-23T00:55:01.92876Z","steps":["trace[1107557191] 'process raft request' (duration: 329.029106ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.9288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.599313Z","time spent":"329.467546ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:01.928873Z","caller":"traceutil/trace.go:171","msg":"trace[427116686] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"327.520579ms","start":"2026-01-23T00:55:01.601347Z","end":"2026-01-23T00:55:01.928868Z","steps":["trace[427116686] 'process raft request' (duration: 327.057698ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.928902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.601332Z","time spent":"327.55416ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:01.928977Z","caller":"traceutil/trace.go:171","msg":"trace[325597635] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"322.054386ms","start":"2026-01-23T00:55:01.606917Z","end":"2026-01-23T00:55:01.928971Z","steps":["trace[325597635] 'process raft request' (duration: 321.512553ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:01.92901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:01.606911Z","time spent":"322.082867ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:01.929135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.548912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-23T00:55:01.92917Z","caller":"traceutil/trace.go:171","msg":"trace[1244520295] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:731; }","duration":"272.596893ms","start":"2026-01-23T00:55:01.656559Z","end":"2026-01-23T00:55:01.929156Z","steps":["trace[1244520295] 'agreement among raft nodes before linearized reading' (duration: 272.51059ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:02.366494Z","caller":"traceutil/trace.go:171","msg":"trace[1609764218] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"365.78788ms","start":"2026-01-23T00:55:02.000689Z","end":"2026-01-23T00:55:02.366477Z","steps":["trace[1609764218] 'process raft request' (duration: 365.671627ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:02.366597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:02.000674Z","time spent":"365.869102ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:02.615649Z","caller":"traceutil/trace.go:171","msg":"trace[1025629868] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"611.244393ms","start":"2026-01-23T00:55:02.00438Z","end":"2026-01-23T00:55:02.615625Z","steps":["trace[1025629868] 'process raft request' (duration: 589.479383ms)","trace[1025629868] 'compare' (duration: 21.449792ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:02.615753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:02.00437Z","time spent":"611.331605ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:03.23459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"439.626247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-23T00:55:03.234635Z","caller":"traceutil/trace.go:171","msg":"trace[7029971] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:733; }","duration":"439.714279ms","start":"2026-01-23T00:55:02.794909Z","end":"2026-01-23T00:55:03.234624Z","steps":["trace[7029971] 'count revisions from in-memory index tree' (duration: 439.567585ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:03.234656Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:02.794893Z","time spent":"439.758229ms","remote":"127.0.0.1:55764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"warn","ts":"2026-01-23T00:55:03.978968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.253192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:03.979023Z","caller":"traceutil/trace.go:171","msg":"trace[1996574797] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:734; }","duration":"103.336275ms","start":"2026-01-23T00:55:03.875673Z","end":"2026-01-23T00:55:03.979009Z","steps":["trace[1996574797] 'range keys from in-memory index tree' (duration: 103.216651ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:04.391523Z","caller":"traceutil/trace.go:171","msg":"trace[1776486371] transaction","detail":"{read_only:false; response_revision:736; number_of_response:1; }","duration":"407.221027ms","start":"2026-01-23T00:55:03.984272Z","end":"2026-01-23T00:55:04.391493Z","steps":["trace[1776486371] 'process raft request' (duration: 383.304385ms)","trace[1776486371] 'compare' (duration: 23.543922ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:04.391683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:03.984268Z","time spent":"407.332829ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:04.391748Z","caller":"traceutil/trace.go:171","msg":"trace[1040861453] transaction","detail":"{read_only:false; response_revision:737; number_of_response:1; }","duration":"407.428352ms","start":"2026-01-23T00:55:03.984298Z","end":"2026-01-23T00:55:04.391726Z","steps":["trace[1040861453] 'process raft request' (duration: 407.093884ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:04.391874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:03.984295Z","time spent":"407.509554ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:04.392096Z","caller":"traceutil/trace.go:171","msg":"trace[1121149894] transaction","detail":"{read_only:false; response_revision:738; number_of_response:1; }","duration":"407.719319ms","start":"2026-01-23T00:55:03.984366Z","end":"2026-01-23T00:55:04.392085Z","steps":["trace[1121149894] 'process raft request' (duration: 407.301819ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:04.392163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:03.984358Z","time spent":"407.76914ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:20.753289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.320934ms","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-01-23T00:55:20.753343Z","caller":"traceutil/trace.go:171","msg":"trace[1253867166] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:784; }","duration":"170.410606ms","start":"2026-01-23T00:55:20.582921Z","end":"2026-01-23T00:55:20.753331Z","steps":["trace[1253867166] 'range keys from in-memory index tree' (duration: 170.216201ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:20.753563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.493217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-23T00:55:20.753577Z","caller":"traceutil/trace.go:171","msg":"trace[2109916186] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:784; }","duration":"687.543799ms","start":"2026-01-23T00:55:20.066028Z","end":"2026-01-23T00:55:20.753572Z","steps":["trace[2109916186] 'range keys from in-memory index tree' (duration: 687.433656ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:20.75359Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:20.066016Z","time spent":"687.57074ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-01-23T00:55:20.754107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.053695ms","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-01-23T00:55:20.754132Z","caller":"traceutil/trace.go:171","msg":"trace[1558516450] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:784; }","duration":"225.102217ms","start":"2026-01-23T00:55:20.529021Z","end":"2026-01-23T00:55:20.754123Z","steps":["trace[1558516450] 'range keys from in-memory index tree' (duration: 224.982054ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:22.039515Z","caller":"traceutil/trace.go:171","msg":"trace[1002840827] linearizableReadLoop","detail":"{readStateIndex:815; appliedIndex:814; }","duration":"208.061172ms","start":"2026-01-23T00:55:21.831428Z","end":"2026-01-23T00:55:22.03949Z","steps":["trace[1002840827] 'read index received' (duration: 207.892208ms)","trace[1002840827] 'applied index is now lower than readState.Index' (duration: 167.754µs)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:22.039551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:21.657665Z","time spent":"381.878522ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-01-23T00:55:22.039733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.283737ms","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-01-23T00:55:22.039768Z","caller":"traceutil/trace.go:171","msg":"trace[915398442] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:788; }","duration":"208.373599ms","start":"2026-01-23T00:55:21.831385Z","end":"2026-01-23T00:55:22.039758Z","steps":["trace[915398442] 'agreement among raft nodes before linearized reading' (duration: 208.220946ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:22.04003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.201462ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:22.040058Z","caller":"traceutil/trace.go:171","msg":"trace[2146256912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:788; }","duration":"161.241473ms","start":"2026-01-23T00:55:21.878807Z","end":"2026-01-23T00:55:22.040049Z","steps":["trace[2146256912] 'agreement among raft nodes before linearized reading' (duration: 161.195892ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:22.533082Z","caller":"traceutil/trace.go:171","msg":"trace[1364209969] transaction","detail":"{read_only:false; response_revision:789; number_of_response:1; }","duration":"490.553304ms","start":"2026-01-23T00:55:22.042507Z","end":"2026-01-23T00:55:22.53306Z","steps":["trace[1364209969] 'process raft request' (duration: 490.41085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:22.533246Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.042482Z","time spent":"490.667756ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:23.062466Z","caller":"traceutil/trace.go:171","msg":"trace[1225076645] linearizableReadLoop","detail":"{readStateIndex:817; appliedIndex:816; }","duration":"526.944071ms","start":"2026-01-23T00:55:22.535492Z","end":"2026-01-23T00:55:23.062436Z","steps":["trace[1225076645] 'read index received' (duration: 491.788706ms)","trace[1225076645] 'applied index is now lower than readState.Index' (duration: 35.153855ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:23.063042Z","caller":"traceutil/trace.go:171","msg":"trace[1494612618] transaction","detail":"{read_only:false; response_revision:790; number_of_response:1; }","duration":"1.012992045s","start":"2026-01-23T00:55:22.049996Z","end":"2026-01-23T00:55:23.062988Z","steps":["trace[1494612618] 'process raft request' (duration: 977.239255ms)","trace[1494612618] 'compare' (duration: 34.767466ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:23.063199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"527.693299ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-01-23T00:55:23.064172Z","caller":"traceutil/trace.go:171","msg":"trace[1405683017] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:790; }","duration":"528.724245ms","start":"2026-01-23T00:55:22.535433Z","end":"2026-01-23T00:55:23.064157Z","steps":["trace[1405683017] 'agreement among raft nodes before linearized reading' (duration: 527.681839ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.064249Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.535417Z","time spent":"528.819277ms","remote":"127.0.0.1:55520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":445,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-01-23T00:55:23.064949Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.049982Z","time spent":"1.014080322s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:23.112552Z","caller":"traceutil/trace.go:171","msg":"trace[1818148446] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"400.418533ms","start":"2026-01-23T00:55:22.712112Z","end":"2026-01-23T00:55:23.11253Z","steps":["trace[1818148446] 'process raft request' (duration: 400.270449ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.112693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.712078Z","time spent":"400.536435ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:23.115076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.989173ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:55:23.115132Z","caller":"traceutil/trace.go:171","msg":"trace[376132378] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:791; }","duration":"324.063445ms","start":"2026-01-23T00:55:22.791057Z","end":"2026-01-23T00:55:23.115121Z","steps":["trace[376132378] 'agreement among raft nodes before linearized reading' (duration: 323.376228ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.115166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.79105Z","time spent":"324.108166ms","remote":"127.0.0.1:55592","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-01-23T00:55:23.115458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.628628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:23.115501Z","caller":"traceutil/trace.go:171","msg":"trace[1617721733] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:791; }","duration":"236.685409ms","start":"2026-01-23T00:55:22.878804Z","end":"2026-01-23T00:55:23.115489Z","steps":["trace[1617721733] 'agreement among raft nodes before linearized reading' (duration: 236.621747ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.116844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.244176ms","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-01-23T00:55:23.116898Z","caller":"traceutil/trace.go:171","msg":"trace[333453809] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:791; }","duration":"351.342538ms","start":"2026-01-23T00:55:22.765543Z","end":"2026-01-23T00:55:23.116885Z","steps":["trace[333453809] 'agreement among raft nodes before linearized reading' (duration: 350.787614ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.117059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.765536Z","time spent":"351.509642ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-23T00:55:23.117755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.519621ms","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-01-23T00:55:23.118315Z","caller":"traceutil/trace.go:171","msg":"trace[1169036897] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:791; }","duration":"353.605763ms","start":"2026-01-23T00:55:22.764191Z","end":"2026-01-23T00:55:23.117797Z","steps":["trace[1169036897] 'agreement among raft nodes before linearized reading' (duration: 353.48504ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.11837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.764183Z","time spent":"354.177647ms","remote":"127.0.0.1:55592","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-01-23T00:55:23.118737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.894079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-23T00:55:23.118764Z","caller":"traceutil/trace.go:171","msg":"trace[661337380] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:791; }","duration":"355.94943ms","start":"2026-01-23T00:55:22.762808Z","end":"2026-01-23T00:55:23.118757Z","steps":["trace[661337380] 'agreement among raft nodes before linearized reading' (duration: 355.879629ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.118915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:22.76279Z","time spent":"356.043532ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-01-23T00:55:23.580217Z","caller":"traceutil/trace.go:171","msg":"trace[2061362375] transaction","detail":"{read_only:false; response_revision:792; number_of_response:1; }","duration":"459.666615ms","start":"2026-01-23T00:55:23.120522Z","end":"2026-01-23T00:55:23.580189Z","steps":["trace[2061362375] 'process raft request' (duration: 385.858939ms)","trace[2061362375] 'compare' (duration: 73.423697ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:23.58035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.120502Z","time spent":"459.777757ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:23.942019Z","caller":"traceutil/trace.go:171","msg":"trace[2103201481] linearizableReadLoop","detail":"{readStateIndex:821; appliedIndex:818; }","duration":"818.168828ms","start":"2026-01-23T00:55:23.123813Z","end":"2026-01-23T00:55:23.941982Z","steps":["trace[2103201481] 'read index received' (duration: 382.578209ms)","trace[2103201481] 'applied index is now lower than readState.Index' (duration: 435.589729ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:23.942124Z","caller":"traceutil/trace.go:171","msg":"trace[1571069913] transaction","detail":"{read_only:false; response_revision:793; number_of_response:1; }","duration":"818.430024ms","start":"2026-01-23T00:55:23.123685Z","end":"2026-01-23T00:55:23.942115Z","steps":["trace[1571069913] 'process raft request' (duration: 818.018584ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.942335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.123673Z","time spent":"818.551657ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:23.942368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.531817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-01-23T00:55:23.942435Z","caller":"traceutil/trace.go:171","msg":"trace[1161419357] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:795; }","duration":"818.616349ms","start":"2026-01-23T00:55:23.123801Z","end":"2026-01-23T00:55:23.942417Z","steps":["trace[1161419357] 'agreement among raft nodes before linearized reading' (duration: 818.503086ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.942472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.123795Z","time spent":"818.66963ms","remote":"127.0.0.1:55594","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-01-23T00:55:23.942571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.539912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-23T00:55:23.942605Z","caller":"traceutil/trace.go:171","msg":"trace[710804561] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:795; }","duration":"316.612754ms","start":"2026-01-23T00:55:23.625983Z","end":"2026-01-23T00:55:23.942595Z","steps":["trace[710804561] 'agreement among raft nodes before linearized reading' (duration: 316.546793ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.942635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.625963Z","time spent":"316.664915ms","remote":"127.0.0.1:55678","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":13,"response size":30,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"info","ts":"2026-01-23T00:55:23.94266Z","caller":"traceutil/trace.go:171","msg":"trace[1764441742] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"818.916416ms","start":"2026-01-23T00:55:23.123732Z","end":"2026-01-23T00:55:23.942648Z","steps":["trace[1764441742] 'process raft request' (duration: 818.084046ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.942745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.123728Z","time spent":"818.960907ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:23.942883Z","caller":"traceutil/trace.go:171","msg":"trace[635075825] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"815.623756ms","start":"2026-01-23T00:55:23.12725Z","end":"2026-01-23T00:55:23.942873Z","steps":["trace[635075825] 'process raft request' (duration: 814.691143ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:23.942932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:23.127243Z","time spent":"815.662057ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:25.106037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.1958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:25.107545Z","caller":"traceutil/trace.go:171","msg":"trace[518831282] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:795; }","duration":"770.738308ms","start":"2026-01-23T00:55:24.336787Z","end":"2026-01-23T00:55:25.107526Z","steps":["trace[518831282] 'count revisions from in-memory index tree' (duration: 769.125818ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:25.107726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:24.33677Z","time spent":"770.892102ms","remote":"127.0.0.1:55710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-01-23T00:55:25.106678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.967252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:25.107832Z","caller":"traceutil/trace.go:171","msg":"trace[1530832868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:795; }","duration":"230.157061ms","start":"2026-01-23T00:55:24.877662Z","end":"2026-01-23T00:55:25.107819Z","steps":["trace[1530832868] 'range keys from in-memory index tree' (duration: 228.919151ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:25.316278Z","caller":"traceutil/trace.go:171","msg":"trace[693075950] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"201.755339ms","start":"2026-01-23T00:55:25.114498Z","end":"2026-01-23T00:55:25.316254Z","steps":["trace[693075950] 'process raft request' (duration: 201.537104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:25.724913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.228892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:55:25.725298Z","caller":"traceutil/trace.go:171","msg":"trace[1955369031] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"140.645452ms","start":"2026-01-23T00:55:25.584606Z","end":"2026-01-23T00:55:25.725251Z","steps":["trace[1955369031] 'range keys from in-memory index tree' (duration: 140.030077ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:25.851969Z","caller":"traceutil/trace.go:171","msg":"trace[239106021] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"121.163386ms","start":"2026-01-23T00:55:25.730772Z","end":"2026-01-23T00:55:25.851935Z","steps":["trace[239106021] 'process raft request' (duration: 121.045134ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:26.748475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"800.285634ms","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-01-23T00:55:26.748547Z","caller":"traceutil/trace.go:171","msg":"trace[548144247] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"800.400416ms","start":"2026-01-23T00:55:25.94813Z","end":"2026-01-23T00:55:26.74853Z","steps":["trace[548144247] 'range keys from in-memory index tree' (duration: 800.10653ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:26.748587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:25.94811Z","time spent":"800.469258ms","remote":"127.0.0.1:55592","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-01-23T00:55:26.748738Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"799.30792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-23T00:55:26.748803Z","caller":"traceutil/trace.go:171","msg":"trace[264845742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:797; }","duration":"799.431743ms","start":"2026-01-23T00:55:25.949353Z","end":"2026-01-23T00:55:26.748785Z","steps":["trace[264845742] 'range keys from in-memory index tree' (duration: 799.165816ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:26.748842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:25.949341Z","time spent":"799.492525ms","remote":"127.0.0.1:55592","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-01-23T00:55:26.749067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"798.581072ms","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-01-23T00:55:26.749097Z","caller":"traceutil/trace.go:171","msg":"trace[1647216264] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:797; }","duration":"798.629424ms","start":"2026-01-23T00:55:25.950459Z","end":"2026-01-23T00:55:26.749088Z","steps":["trace[1647216264] 'range keys from in-memory index tree' (duration: 798.49955ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:26.749123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:25.950446Z","time spent":"798.670625ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-01-23T00:55:26.749457Z","caller":"traceutil/trace.go:171","msg":"trace[1197074041] transaction","detail":"{read_only:false; response_revision:798; number_of_response:1; }","duration":"279.276615ms","start":"2026-01-23T00:55:26.470167Z","end":"2026-01-23T00:55:26.749443Z","steps":["trace[1197074041] 'process raft request' (duration: 229.895894ms)","trace[1197074041] 'compare' (duration: 49.066084ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:36.974763Z","caller":"traceutil/trace.go:171","msg":"trace[971062074] transaction","detail":"{read_only:false; response_revision:877; number_of_response:1; }","duration":"118.703428ms","start":"2026-01-23T00:55:36.856032Z","end":"2026-01-23T00:55:36.974735Z","steps":["trace[971062074] 'process raft request' (duration: 118.603135ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:36.978359Z","caller":"traceutil/trace.go:171","msg":"trace[30778822] linearizableReadLoop","detail":"{readStateIndex:910; appliedIndex:907; }","duration":"102.497314ms","start":"2026-01-23T00:55:36.875842Z","end":"2026-01-23T00:55:36.978339Z","steps":["trace[30778822] 'read index received' (duration: 98.862995ms)","trace[30778822] 'applied index is now lower than readState.Index' (duration: 3.633698ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:36.978443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.579525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:55:36.978465Z","caller":"traceutil/trace.go:171","msg":"trace[717750749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:879; }","duration":"102.639287ms","start":"2026-01-23T00:55:36.87582Z","end":"2026-01-23T00:55:36.978459Z","steps":["trace[717750749] 'agreement among raft nodes before linearized reading' (duration: 102.564756ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:36.978646Z","caller":"traceutil/trace.go:171","msg":"trace[196948833] transaction","detail":"{read_only:false; response_revision:878; number_of_response:1; }","duration":"122.088871ms","start":"2026-01-23T00:55:36.856551Z","end":"2026-01-23T00:55:36.97864Z","steps":["trace[196948833] 'process raft request' (duration: 121.694371ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:36.978737Z","caller":"traceutil/trace.go:171","msg":"trace[1117637585] transaction","detail":"{read_only:false; response_revision:879; number_of_response:1; }","duration":"120.355028ms","start":"2026-01-23T00:55:36.858366Z","end":"2026-01-23T00:55:36.978721Z","steps":["trace[1117637585] 'process raft request' (duration: 119.951748ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:37.348958Z","caller":"traceutil/trace.go:171","msg":"trace[1760958454] transaction","detail":"{read_only:false; response_revision:880; number_of_response:1; }","duration":"277.167694ms","start":"2026-01-23T00:55:37.071774Z","end":"2026-01-23T00:55:37.348941Z","steps":["trace[1760958454] 'process raft request' (duration: 249.748677ms)","trace[1760958454] 'compare' (duration: 27.353866ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:37.790639Z","caller":"traceutil/trace.go:171","msg":"trace[1197586927] transaction","detail":"{read_only:false; response_revision:881; number_of_response:1; }","duration":"335.974025ms","start":"2026-01-23T00:55:37.454649Z","end":"2026-01-23T00:55:37.790623Z","steps":["trace[1197586927] 'process raft request' (duration: 335.870482ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:37.790745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:37.454625Z","time spent":"336.068026ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:39.271173Z","caller":"traceutil/trace.go:171","msg":"trace[1593331210] transaction","detail":"{read_only:false; response_revision:883; number_of_response:1; }","duration":"291.333558ms","start":"2026-01-23T00:55:38.979833Z","end":"2026-01-23T00:55:39.271166Z","steps":["trace[1593331210] 'process raft request' (duration: 291.0063ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:39.270973Z","caller":"traceutil/trace.go:171","msg":"trace[306072733] linearizableReadLoop","detail":"{readStateIndex:915; appliedIndex:914; }","duration":"288.600812ms","start":"2026-01-23T00:55:38.982356Z","end":"2026-01-23T00:55:39.270957Z","steps":["trace[306072733] 'read index received' (duration: 288.435128ms)","trace[306072733] 'applied index is now lower than readState.Index' (duration: 164.804µs)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:39.27148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.92659ms","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-01-23T00:55:39.271501Z","caller":"traceutil/trace.go:171","msg":"trace[1550959967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:883; }","duration":"288.977531ms","start":"2026-01-23T00:55:38.982518Z","end":"2026-01-23T00:55:39.271496Z","steps":["trace[1550959967] 'agreement among raft nodes before linearized reading' (duration: 288.893029ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:39.271632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.273738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-01-23T00:55:39.271652Z","caller":"traceutil/trace.go:171","msg":"trace[968252004] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:883; }","duration":"289.306619ms","start":"2026-01-23T00:55:38.982341Z","end":"2026-01-23T00:55:39.271647Z","steps":["trace[968252004] 'agreement among raft nodes before linearized reading' (duration: 289.050583ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:39.922249Z","caller":"traceutil/trace.go:171","msg":"trace[820478191] transaction","detail":"{read_only:false; response_revision:884; number_of_response:1; }","duration":"647.996207ms","start":"2026-01-23T00:55:39.274235Z","end":"2026-01-23T00:55:39.922231Z","steps":["trace[820478191] 'process raft request' (duration: 588.51687ms)","trace[820478191] 'compare' (duration: 59.400265ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:39.922412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:39.274217Z","time spent":"648.138611ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:40.102011Z","caller":"traceutil/trace.go:171","msg":"trace[1147381288] linearizableReadLoop","detail":"{readStateIndex:917; appliedIndex:915; }","duration":"306.842686ms","start":"2026-01-23T00:55:39.79515Z","end":"2026-01-23T00:55:40.101993Z","steps":["trace[1147381288] 'read index received' (duration: 67.559544ms)","trace[1147381288] 'applied index is now lower than readState.Index' (duration: 239.280892ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:40.102257Z","caller":"traceutil/trace.go:171","msg":"trace[13795880] transaction","detail":"{read_only:false; response_revision:885; number_of_response:1; }","duration":"826.629563ms","start":"2026-01-23T00:55:39.275618Z","end":"2026-01-23T00:55:40.102248Z","steps":["trace[13795880] 'process raft request' (duration: 809.914866ms)","trace[13795880] 'compare' (duration: 16.386069ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:40.10236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:39.275605Z","time spent":"826.704506ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:40.102555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.41791ms","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-01-23T00:55:40.102583Z","caller":"traceutil/trace.go:171","msg":"trace[1659038004] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:885; }","duration":"307.478621ms","start":"2026-01-23T00:55:39.795098Z","end":"2026-01-23T00:55:40.102577Z","steps":["trace[1659038004] 'agreement among raft nodes before linearized reading' (duration: 307.381269ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:40.102604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:39.795078Z","time spent":"307.522243ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-01-23T00:55:40.102727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.50823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-01-23T00:55:40.102749Z","caller":"traceutil/trace.go:171","msg":"trace[2077344231] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:885; }","duration":"198.554791ms","start":"2026-01-23T00:55:39.904189Z","end":"2026-01-23T00:55:40.102744Z","steps":["trace[2077344231] 'agreement among raft nodes before linearized reading' (duration: 198.50018ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:40.916484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"552.975255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:55:40.916942Z","caller":"traceutil/trace.go:171","msg":"trace[725198170] transaction","detail":"{read_only:false; response_revision:886; number_of_response:1; }","duration":"811.016222ms","start":"2026-01-23T00:55:40.105908Z","end":"2026-01-23T00:55:40.916924Z","steps":["trace[725198170] 'process raft request' (duration: 257.536866ms)","trace[725198170] 'compare' (duration: 552.534103ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:40.917021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:40.105893Z","time spent":"811.093544ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:40.917158Z","caller":"traceutil/trace.go:171","msg":"trace[38105649] transaction","detail":"{read_only:false; response_revision:887; number_of_response:1; }","duration":"809.673399ms","start":"2026-01-23T00:55:40.107475Z","end":"2026-01-23T00:55:40.917148Z","steps":["trace[38105649] 'process raft request' (duration: 809.095085ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:40.917206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:40.107467Z","time spent":"809.713511ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:41.447306Z","caller":"traceutil/trace.go:171","msg":"trace[1296196390] transaction","detail":"{read_only:false; response_revision:888; number_of_response:1; }","duration":"169.493364ms","start":"2026-01-23T00:55:41.277773Z","end":"2026-01-23T00:55:41.447266Z","steps":["trace[1296196390] 'process raft request' (duration: 169.355411ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:41.898433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.275057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.207\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-01-23T00:55:41.89848Z","caller":"traceutil/trace.go:171","msg":"trace[184452734] range","detail":"{range_begin:/registry/masterleases/162.253.55.207; range_end:; response_count:1; response_revision:888; }","duration":"240.363339ms","start":"2026-01-23T00:55:41.658106Z","end":"2026-01-23T00:55:41.898469Z","steps":["trace[184452734] 'range keys from in-memory index tree' (duration: 240.060721ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:49.964841Z","caller":"traceutil/trace.go:171","msg":"trace[111552212] transaction","detail":"{read_only:false; response_revision:911; number_of_response:1; }","duration":"453.974755ms","start":"2026-01-23T00:55:49.510852Z","end":"2026-01-23T00:55:49.964827Z","steps":["trace[111552212] 'process raft request' (duration: 453.704319ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:49.964921Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:49.510841Z","time spent":"454.035947ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:52.108765Z","caller":"traceutil/trace.go:171","msg":"trace[723368529] transaction","detail":"{read_only:false; response_revision:917; number_of_response:1; }","duration":"138.848426ms","start":"2026-01-23T00:55:51.9699Z","end":"2026-01-23T00:55:52.108749Z","steps":["trace[723368529] 'process raft request' (duration: 138.501988ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:55:54.334337Z","caller":"traceutil/trace.go:171","msg":"trace[856190380] linearizableReadLoop","detail":"{readStateIndex:957; appliedIndex:956; }","duration":"221.459697ms","start":"2026-01-23T00:55:54.112858Z","end":"2026-01-23T00:55:54.334318Z","steps":["trace[856190380] 'read index received' (duration: 221.310923ms)","trace[856190380] 'applied index is now lower than readState.Index' (duration: 148.264µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:55:54.334403Z","caller":"traceutil/trace.go:171","msg":"trace[759409362] transaction","detail":"{read_only:false; response_revision:922; number_of_response:1; }","duration":"436.384712ms","start":"2026-01-23T00:55:53.898013Z","end":"2026-01-23T00:55:54.334398Z","steps":["trace[759409362] 'process raft request' (duration: 436.198507ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:54.334471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:53.897998Z","time spent":"436.418732ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:55:54.334601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.741403ms","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-01-23T00:55:54.334616Z","caller":"traceutil/trace.go:171","msg":"trace[1778446609] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:922; }","duration":"221.777704ms","start":"2026-01-23T00:55:54.112833Z","end":"2026-01-23T00:55:54.334611Z","steps":["trace[1778446609] 'agreement among raft nodes before linearized reading' (duration: 221.704062ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:54.334687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.071714ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-01-23T00:55:54.334698Z","caller":"traceutil/trace.go:171","msg":"trace[193134481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:922; }","duration":"220.093835ms","start":"2026-01-23T00:55:54.114601Z","end":"2026-01-23T00:55:54.334695Z","steps":["trace[193134481] 'agreement among raft nodes before linearized reading' (duration: 220.054104ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:54.908198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.25863ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:55:54.908474Z","caller":"traceutil/trace.go:171","msg":"trace[1946713852] transaction","detail":"{read_only:false; response_revision:923; number_of_response:1; }","duration":"570.018366ms","start":"2026-01-23T00:55:54.338444Z","end":"2026-01-23T00:55:54.908463Z","steps":["trace[1946713852] 'process raft request' (duration: 355.452089ms)","trace[1946713852] 'compare' (duration: 214.203449ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:55:54.908509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:54.338431Z","time spent":"570.060768ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:54.908566Z","caller":"traceutil/trace.go:171","msg":"trace[1894202498] transaction","detail":"{read_only:false; response_revision:924; number_of_response:1; }","duration":"569.537385ms","start":"2026-01-23T00:55:54.339025Z","end":"2026-01-23T00:55:54.908562Z","steps":["trace[1894202498] 'process raft request' (duration: 569.228308ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:54.908587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:54.339018Z","time spent":"569.558456ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:55:54.908643Z","caller":"traceutil/trace.go:171","msg":"trace[732947604] transaction","detail":"{read_only:false; response_revision:925; number_of_response:1; }","duration":"569.580406ms","start":"2026-01-23T00:55:54.339058Z","end":"2026-01-23T00:55:54.908639Z","steps":["trace[732947604] 'process raft request' (duration: 569.214578ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:55:54.908664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:55:54.339055Z","time spent":"569.597077ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:56:02.390895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.544768ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:56:02.390949Z","caller":"traceutil/trace.go:171","msg":"trace[1019332298] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:1000; }","duration":"515.598496ms","start":"2026-01-23T00:56:01.875341Z","end":"2026-01-23T00:56:02.390939Z","steps":["trace[1019332298] 'read index received' (duration: 30.1µs)","trace[1019332298] 'applied index is now lower than readState.Index' (duration: 515.567786ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:56:02.391016Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.681798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:56:02.391028Z","caller":"traceutil/trace.go:171","msg":"trace[37967229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:964; }","duration":"515.721329ms","start":"2026-01-23T00:56:01.875302Z","end":"2026-01-23T00:56:02.391024Z","steps":["trace[37967229] 'agreement among raft nodes before linearized reading' (duration: 515.662417ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:02.391043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:01.875285Z","time spent":"515.75443ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-23T00:56:02.391153Z","caller":"traceutil/trace.go:171","msg":"trace[1053598637] transaction","detail":"{read_only:false; response_revision:964; number_of_response:1; }","duration":"682.852706ms","start":"2026-01-23T00:56:01.708295Z","end":"2026-01-23T00:56:02.391148Z","steps":["trace[1053598637] 'process raft request' (duration: 108.008111ms)","trace[1053598637] 'compare' (duration: 574.321163ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:56:02.39118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:01.708283Z","time spent":"682.881937ms","remote":"127.0.0.1:55368","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:56:03.453262Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":11978057581155892313,"retry-timeout":"500ms"} {"level":"info","ts":"2026-01-23T00:56:03.794966Z","caller":"traceutil/trace.go:171","msg":"trace[143319052] transaction","detail":"{read_only:false; response_revision:965; number_of_response:1; }","duration":"845.38958ms","start":"2026-01-23T00:56:02.949553Z","end":"2026-01-23T00:56:03.794942Z","steps":["trace[143319052] 'process raft request' (duration: 845.248236ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:03.795112Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:02.949541Z","time spent":"845.498932ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:56:04.056703Z","caller":"traceutil/trace.go:171","msg":"trace[130869396] linearizableReadLoop","detail":"{readStateIndex:1003; appliedIndex:1001; }","duration":"1.104344459s","start":"2026-01-23T00:56:02.952342Z","end":"2026-01-23T00:56:04.056686Z","steps":["trace[130869396] 'read index received' (duration: 842.578196ms)","trace[130869396] 'applied index is now lower than readState.Index' (duration: 261.765583ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:56:04.056866Z","caller":"traceutil/trace.go:171","msg":"trace[1656111357] transaction","detail":"{read_only:false; response_revision:966; number_of_response:1; }","duration":"1.104587414s","start":"2026-01-23T00:56:02.95227Z","end":"2026-01-23T00:56:04.056857Z","steps":["trace[1656111357] 'process raft request' (duration: 1.092383126s)","trace[1656111357] 'compare' (duration: 11.773008ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:56:04.056934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:02.952249Z","time spent":"1.104636816s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:56:04.057101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.104757639s","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-01-23T00:56:04.057122Z","caller":"traceutil/trace.go:171","msg":"trace[2138561456] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:966; }","duration":"1.104777909s","start":"2026-01-23T00:56:02.952338Z","end":"2026-01-23T00:56:04.057116Z","steps":["trace[2138561456] 'agreement among raft nodes before linearized reading' (duration: 1.104701847s)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.057141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:02.952331Z","time spent":"1.10480476s","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-01-23T00:56:04.057252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"838.091873ms","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-01-23T00:56:04.057271Z","caller":"traceutil/trace.go:171","msg":"trace[1592248645] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:966; }","duration":"838.144755ms","start":"2026-01-23T00:56:03.21912Z","end":"2026-01-23T00:56:04.057265Z","steps":["trace[1592248645] 'agreement among raft nodes before linearized reading' (duration: 838.092803ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.057286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:03.219101Z","time spent":"838.182175ms","remote":"127.0.0.1:55592","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-01-23T00:56:04.057429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.810162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:56:04.057449Z","caller":"traceutil/trace.go:171","msg":"trace[755430354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:966; }","duration":"181.848133ms","start":"2026-01-23T00:56:03.875596Z","end":"2026-01-23T00:56:04.057444Z","steps":["trace[755430354] 'agreement among raft nodes before linearized reading' (duration: 181.818982ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.057539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.515652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-01-23T00:56:04.057555Z","caller":"traceutil/trace.go:171","msg":"trace[841315758] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:966; }","duration":"309.564323ms","start":"2026-01-23T00:56:03.747986Z","end":"2026-01-23T00:56:04.05755Z","steps":["trace[841315758] 'agreement among raft nodes before linearized reading' (duration: 309.535492ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.057571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:03.747968Z","time spent":"309.598413ms","remote":"127.0.0.1:55520","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":4,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-01-23T00:56:04.057678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"786.115509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-01-23T00:56:04.057694Z","caller":"traceutil/trace.go:171","msg":"trace[1395976344] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:966; }","duration":"786.17034ms","start":"2026-01-23T00:56:03.271519Z","end":"2026-01-23T00:56:04.05769Z","steps":["trace[1395976344] 'agreement among raft nodes before linearized reading' (duration: 786.122369ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.05771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:03.271501Z","time spent":"786.205282ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-01-23T00:56:04.671979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"464.021481ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-01-23T00:56:04.672067Z","caller":"traceutil/trace.go:171","msg":"trace[1869678413] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"604.711757ms","start":"2026-01-23T00:56:04.067346Z","end":"2026-01-23T00:56:04.672058Z","steps":["trace[1869678413] 'process raft request' (duration: 604.683836ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:04.672099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:04.067338Z","time spent":"604.746037ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-01-23T00:56:04.67224Z","caller":"traceutil/trace.go:171","msg":"trace[1263575676] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"606.6263ms","start":"2026-01-23T00:56:04.065607Z","end":"2026-01-23T00:56:04.672234Z","steps":["trace[1263575676] 'process raft request' (duration: 142.305792ms)","trace[1263575676] 'compare' (duration: 463.753185ms)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:56:04.672264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:04.065599Z","time spent":"606.652551ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:56:05.396325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.013477ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:56:05.396399Z","caller":"traceutil/trace.go:171","msg":"trace[1034557018] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:970; }","duration":"240.114829ms","start":"2026-01-23T00:56:05.156267Z","end":"2026-01-23T00:56:05.396382Z","steps":["trace[1034557018] 'count revisions from in-memory index tree' (duration: 239.968516ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:05.397197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.602021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-01-23T00:56:05.397243Z","caller":"traceutil/trace.go:171","msg":"trace[1156668042] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"519.581465ms","start":"2026-01-23T00:56:04.877649Z","end":"2026-01-23T00:56:05.397231Z","steps":["trace[1156668042] 'range keys from in-memory index tree' (duration: 517.558479ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:05.397285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:56:04.877637Z","time spent":"519.636707ms","remote":"127.0.0.1:55338","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-01-23T00:56:14.902623Z","caller":"traceutil/trace.go:171","msg":"trace[1750193269] linearizableReadLoop","detail":"{readStateIndex:1044; appliedIndex:1043; }","duration":"185.033035ms","start":"2026-01-23T00:56:14.717559Z","end":"2026-01-23T00:56:14.902592Z","steps":["trace[1750193269] 'read index received' (duration: 184.882272ms)","trace[1750193269] 'applied index is now lower than readState.Index' (duration: 149.993µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:56:14.902736Z","caller":"traceutil/trace.go:171","msg":"trace[1751023417] transaction","detail":"{read_only:false; response_revision:1005; number_of_response:1; }","duration":"186.015747ms","start":"2026-01-23T00:56:14.71671Z","end":"2026-01-23T00:56:14.902726Z","steps":["trace[1751023417] 'process raft request' (duration: 185.772162ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:56:14.902826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.25394ms","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-01-23T00:56:14.902881Z","caller":"traceutil/trace.go:171","msg":"trace[1156529998] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1005; }","duration":"185.337933ms","start":"2026-01-23T00:56:14.717536Z","end":"2026-01-23T00:56:14.902873Z","steps":["trace[1156529998] 'agreement among raft nodes before linearized reading' (duration: 185.191229ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:56:16.444879Z","caller":"traceutil/trace.go:171","msg":"trace[757781056] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"225.680632ms","start":"2026-01-23T00:56:16.219172Z","end":"2026-01-23T00:56:16.444853Z","steps":["trace[757781056] 'process raft request' (duration: 193.834656ms)","trace[757781056] 'compare' (duration: 31.518888ms)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:56:20.518209Z","caller":"traceutil/trace.go:171","msg":"trace[388155392] linearizableReadLoop","detail":"{readStateIndex:1075; appliedIndex:1074; }","duration":"238.341869ms","start":"2026-01-23T00:56:20.279846Z","end":"2026-01-23T00:56:20.518188Z","steps":["trace[388155392] 'read index received' (duration: 238.033422ms)","trace[388155392] 'applied index is now lower than readState.Index' (duration: 307.477µs)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:56:20.51856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.700938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-01-23T00:56:20.51873Z","caller":"traceutil/trace.go:171","msg":"trace[242404713] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1035; }","duration":"238.881902ms","start":"2026-01-23T00:56:20.279833Z","end":"2026-01-23T00:56:20.518715Z","steps":["trace[242404713] 'agreement among raft nodes before linearized reading' (duration: 238.576195ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:56:20.519035Z","caller":"traceutil/trace.go:171","msg":"trace[1673016611] transaction","detail":"{read_only:false; response_revision:1035; number_of_response:1; }","duration":"276.800646ms","start":"2026-01-23T00:56:20.242222Z","end":"2026-01-23T00:56:20.519022Z","steps":["trace[1673016611] 'process raft request' (duration: 275.706691ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:58:13.161665Z","caller":"traceutil/trace.go:171","msg":"trace[829733509] transaction","detail":"{read_only:false; response_revision:1514; number_of_response:1; }","duration":"135.937054ms","start":"2026-01-23T00:58:13.025713Z","end":"2026-01-23T00:58:13.16165Z","steps":["trace[829733509] 'process raft request' (duration: 135.79085ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:58:13.314752Z","caller":"traceutil/trace.go:171","msg":"trace[202260817] transaction","detail":"{read_only:false; response_revision:1515; number_of_response:1; }","duration":"147.505821ms","start":"2026-01-23T00:58:13.167231Z","end":"2026-01-23T00:58:13.314737Z","steps":["trace[202260817] 'process raft request' (duration: 145.638778ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:58:13.314915Z","caller":"traceutil/trace.go:171","msg":"trace[555979839] transaction","detail":"{read_only:false; response_revision:1516; number_of_response:1; }","duration":"147.413849ms","start":"2026-01-23T00:58:13.167495Z","end":"2026-01-23T00:58:13.314909Z","steps":["trace[555979839] 'process raft request' (duration: 147.093511ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:59:13.973013Z","caller":"traceutil/trace.go:171","msg":"trace[1658372055] linearizableReadLoop","detail":"{readStateIndex:1865; appliedIndex:1864; }","duration":"103.125633ms","start":"2026-01-23T00:59:13.86987Z","end":"2026-01-23T00:59:13.972996Z","steps":["trace[1658372055] 'read index received' (duration: 102.946279ms)","trace[1658372055] 'applied index is now lower than readState.Index' (duration: 178.554µs)"],"step_count":2} {"level":"warn","ts":"2026-01-23T00:59:13.973181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.290037ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-23T00:59:13.973202Z","caller":"traceutil/trace.go:171","msg":"trace[1014006028] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1789; }","duration":"103.339657ms","start":"2026-01-23T00:59:13.869854Z","end":"2026-01-23T00:59:13.973194Z","steps":["trace[1014006028] 'agreement among raft nodes before linearized reading' (duration: 103.218635ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:59:13.973272Z","caller":"traceutil/trace.go:171","msg":"trace[894239956] transaction","detail":"{read_only:false; response_revision:1789; number_of_response:1; }","duration":"105.872116ms","start":"2026-01-23T00:59:13.86737Z","end":"2026-01-23T00:59:13.973242Z","steps":["trace[894239956] 'process raft request' (duration: 105.499147ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:59:14.17105Z","caller":"traceutil/trace.go:171","msg":"trace[563635038] transaction","detail":"{read_only:false; response_revision:1790; number_of_response:1; }","duration":"194.154399ms","start":"2026-01-23T00:59:13.976875Z","end":"2026-01-23T00:59:14.171029Z","steps":["trace[563635038] 'process raft request' (duration: 194.047006ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:59:16.297897Z","caller":"traceutil/trace.go:171","msg":"trace[211446906] linearizableReadLoop","detail":"{readStateIndex:1872; appliedIndex:1871; }","duration":"211.946946ms","start":"2026-01-23T00:59:16.085933Z","end":"2026-01-23T00:59:16.29788Z","steps":["trace[211446906] 'read index received' (duration: 211.812003ms)","trace[211446906] 'applied index is now lower than readState.Index' (duration: 134.283µs)"],"step_count":2} {"level":"info","ts":"2026-01-23T00:59:16.298093Z","caller":"traceutil/trace.go:171","msg":"trace[1791620570] transaction","detail":"{read_only:false; response_revision:1796; number_of_response:1; }","duration":"318.279293ms","start":"2026-01-23T00:59:15.979807Z","end":"2026-01-23T00:59:16.298086Z","steps":["trace[1791620570] 'process raft request' (duration: 317.986706ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:59:16.298156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-01-23T00:59:15.979793Z","time spent":"318.316474ms","remote":"127.0.0.1:55592","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-01-23T00:59:16.298361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.425217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-01-23T00:59:16.298384Z","caller":"traceutil/trace.go:171","msg":"trace[2076990799] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1796; }","duration":"212.480109ms","start":"2026-01-23T00:59:16.085893Z","end":"2026-01-23T00:59:16.298373Z","steps":["trace[2076990799] 'agreement among raft nodes before linearized reading' (duration: 212.404047ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:59:16.2985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.433861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-01-23T00:59:16.298565Z","caller":"traceutil/trace.go:171","msg":"trace[1087652771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1796; }","duration":"124.539684ms","start":"2026-01-23T00:59:16.174008Z","end":"2026-01-23T00:59:16.298548Z","steps":["trace[1087652771] 'agreement among raft nodes before linearized reading' (duration: 124.330839ms)"],"step_count":1} {"level":"warn","ts":"2026-01-23T00:59:16.865366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.362177ms","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-01-23T00:59:16.865441Z","caller":"traceutil/trace.go:171","msg":"trace[598608521] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1799; }","duration":"257.5066ms","start":"2026-01-23T00:59:16.607917Z","end":"2026-01-23T00:59:16.865424Z","steps":["trace[598608521] 'range keys from in-memory index tree' (duration: 257.128642ms)"],"step_count":1} {"level":"info","ts":"2026-01-23T00:59:22.522783Z","caller":"traceutil/trace.go:171","msg":"trace[938398923] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"165.210095ms","start":"2026-01-23T00:59:22.357558Z","end":"2026-01-23T00:59:22.522768Z","steps":["trace[938398923] 'process raft request' (duration: 145.710699ms)"],"step_count":1}