{"level":"warn","ts":"2026-02-10T18:53:49.108237Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:53:49.108506Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.106:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.106:2380","--initial-cluster=instance=https://199.19.213.106:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.106:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.106:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-10T18:53:49.108741Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-10T18:53:49.108833Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.106:2380"]} {"level":"info","ts":"2026-02-10T18:53:49.108905Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T18:53:49.109744Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.106:2379"]} {"level":"info","ts":"2026-02-10T18:53:49.109942Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":2,"max-cpu-available":2,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.106:2380"],"listen-peer-urls":["https://199.19.213.106:2380"],"advertise-client-urls":["https://199.19.213.106:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.106:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.106:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-10T18:53:49.117778Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"7.463828ms"} {"level":"info","ts":"2026-02-10T18:53:49.140559Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"c2e3903e4ff7350b","cluster-id":"18da11c3c6a3dea7"} {"level":"info","ts":"2026-02-10T18:53:49.140729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b switched to configuration voters=()"} {"level":"info","ts":"2026-02-10T18:53:49.141553Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b became follower at term 0"} {"level":"info","ts":"2026-02-10T18:53:49.141653Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft c2e3903e4ff7350b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-10T18:53:49.141701Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b became follower at term 1"} {"level":"info","ts":"2026-02-10T18:53:49.141809Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b switched to configuration voters=(14043226660375311627)"} {"level":"warn","ts":"2026-02-10T18:53:49.150343Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-10T18:53:49.152525Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-10T18:53:49.156024Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-10T18:53:49.159293Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"c2e3903e4ff7350b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-10T18:53:49.162699Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"c2e3903e4ff7350b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-10T18:53:49.162925Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:53:49.16297Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:53:49.162977Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-10T18:53:49.163202Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b switched to configuration voters=(14043226660375311627)"} {"level":"info","ts":"2026-02-10T18:53:49.163586Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"18da11c3c6a3dea7","local-member-id":"c2e3903e4ff7350b","added-peer-id":"c2e3903e4ff7350b","added-peer-peer-urls":["https://199.19.213.106:2380"]} {"level":"info","ts":"2026-02-10T18:53:49.175583Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-10T18:53:49.176038Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"c2e3903e4ff7350b","initial-advertise-peer-urls":["https://199.19.213.106:2380"],"listen-peer-urls":["https://199.19.213.106:2380"],"advertise-client-urls":["https://199.19.213.106:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.106:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-10T18:53:49.176063Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-10T18:53:49.176622Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.106:2380"} {"level":"info","ts":"2026-02-10T18:53:49.176646Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.106:2380"} {"level":"info","ts":"2026-02-10T18:53:49.342554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b is starting a new election at term 1"} {"level":"info","ts":"2026-02-10T18:53:49.342592Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b became pre-candidate at term 1"} {"level":"info","ts":"2026-02-10T18:53:49.342617Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b received MsgPreVoteResp from c2e3903e4ff7350b at term 1"} {"level":"info","ts":"2026-02-10T18:53:49.342628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b became candidate at term 2"} {"level":"info","ts":"2026-02-10T18:53:49.342633Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b received MsgVoteResp from c2e3903e4ff7350b at term 2"} {"level":"info","ts":"2026-02-10T18:53:49.342725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"c2e3903e4ff7350b became leader at term 2"} {"level":"info","ts":"2026-02-10T18:53:49.342733Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: c2e3903e4ff7350b elected leader c2e3903e4ff7350b at term 2"} {"level":"info","ts":"2026-02-10T18:53:49.346219Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"c2e3903e4ff7350b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.106:2379]}","request-path":"/0/members/c2e3903e4ff7350b/attributes","cluster-id":"18da11c3c6a3dea7","publish-timeout":"7s"} {"level":"info","ts":"2026-02-10T18:53:49.346442Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:49.347281Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:53:49.347588Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"18da11c3c6a3dea7","local-member-id":"c2e3903e4ff7350b","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:49.347601Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-10T18:53:49.347662Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-10T18:53:49.347715Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:49.347673Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-10T18:53:49.34779Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-10T18:53:49.351863Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-10T18:53:49.352557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.106:2379"} {"level":"info","ts":"2026-02-10T18:54:07.931678Z","caller":"traceutil/trace.go:171","msg":"trace[1660958370] transaction","detail":"{read_only:false; response_revision:410; number_of_response:1; }","duration":"101.24372ms","start":"2026-02-10T18:54:07.830416Z","end":"2026-02-10T18:54:07.93166Z","steps":["trace[1660958370] 'process raft request' (duration: 99.908608ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:08.362719Z","caller":"traceutil/trace.go:171","msg":"trace[2096056235] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"411.602062ms","start":"2026-02-10T18:54:07.951091Z","end":"2026-02-10T18:54:08.362693Z","steps":["trace[2096056235] 'process raft request' (duration: 331.745645ms)","trace[2096056235] 'compare' (duration: 79.422334ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:08.363345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:07.951074Z","time spent":"411.765787ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:08.810293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.664622ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-10T18:54:08.810406Z","caller":"traceutil/trace.go:171","msg":"trace[155008493] linearizableReadLoop","detail":"{readStateIndex:423; appliedIndex:422; }","duration":"117.24652ms","start":"2026-02-10T18:54:08.693143Z","end":"2026-02-10T18:54:08.81039Z","steps":["trace[155008493] 'read index received' (duration: 26.841µs)","trace[155008493] 'applied index is now lower than readState.Index' (duration: 117.218159ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:08.810522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.348363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:08.81055Z","caller":"traceutil/trace.go:171","msg":"trace[1197827948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:411; }","duration":"117.427496ms","start":"2026-02-10T18:54:08.693112Z","end":"2026-02-10T18:54:08.810539Z","steps":["trace[1197827948] 'agreement among raft nodes before linearized reading' (duration: 117.355504ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.178298Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3822320545682348357,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:54:22.566195Z","caller":"traceutil/trace.go:171","msg":"trace[1596529075] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"894.118666ms","start":"2026-02-10T18:54:21.672047Z","end":"2026-02-10T18:54:22.566166Z","steps":["trace[1596529075] 'process raft request' (duration: 893.955971ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.566375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:21.672032Z","time spent":"894.2326ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9305,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:22.579157Z","caller":"traceutil/trace.go:171","msg":"trace[1244730077] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"796.040717ms","start":"2026-02-10T18:54:21.783096Z","end":"2026-02-10T18:54:22.579137Z","steps":["trace[1244730077] 'process raft request' (duration: 795.718587ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.57925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:21.78308Z","time spent":"796.12645ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:54:22.579541Z","caller":"traceutil/trace.go:171","msg":"trace[242984475] transaction","detail":"{read_only:false; response_revision:506; number_of_response:1; }","duration":"594.59702ms","start":"2026-02-10T18:54:21.984937Z","end":"2026-02-10T18:54:22.579534Z","steps":["trace[242984475] 'process raft request' (duration: 594.035792ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.579586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:21.984911Z","time spent":"594.653612ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:54:22.579801Z","caller":"traceutil/trace.go:171","msg":"trace[270229423] transaction","detail":"{read_only:false; response_revision:507; number_of_response:1; }","duration":"393.873216ms","start":"2026-02-10T18:54:22.185918Z","end":"2026-02-10T18:54:22.579791Z","steps":["trace[270229423] 'process raft request' (duration: 393.118232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.579838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:22.18589Z","time spent":"393.932298ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:54:22.58009Z","caller":"traceutil/trace.go:171","msg":"trace[221751204] linearizableReadLoop","detail":"{readStateIndex:520; appliedIndex:518; }","duration":"902.08895ms","start":"2026-02-10T18:54:21.677991Z","end":"2026-02-10T18:54:22.58008Z","steps":["trace[221751204] 'read index received' (duration: 889.588032ms)","trace[221751204] 'applied index is now lower than readState.Index' (duration: 12.500148ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:22.580184Z","caller":"traceutil/trace.go:171","msg":"trace[1321086491] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"282.551084ms","start":"2026-02-10T18:54:22.297625Z","end":"2026-02-10T18:54:22.580176Z","steps":["trace[1321086491] 'process raft request' (duration: 281.450029ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.580381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.093812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:22.58041Z","caller":"traceutil/trace.go:171","msg":"trace[691647485] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:508; }","duration":"199.151594ms","start":"2026-02-10T18:54:22.38125Z","end":"2026-02-10T18:54:22.580401Z","steps":["trace[691647485] 'agreement among raft nodes before linearized reading' (duration: 199.112612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.580505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.218206ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T18:54:22.580529Z","caller":"traceutil/trace.go:171","msg":"trace[909910451] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:508; }","duration":"577.279278ms","start":"2026-02-10T18:54:22.003244Z","end":"2026-02-10T18:54:22.580524Z","steps":["trace[909910451] 'agreement among raft nodes before linearized reading' (duration: 577.237466ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.580548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:22.003223Z","time spent":"577.318979ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:54:22.580235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.239375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:54:22.580799Z","caller":"traceutil/trace.go:171","msg":"trace[1902092159] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:508; }","duration":"902.838785ms","start":"2026-02-10T18:54:21.677947Z","end":"2026-02-10T18:54:22.580786Z","steps":["trace[1902092159] 'agreement among raft nodes before linearized reading' (duration: 902.258936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.580827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:21.677928Z","time spent":"902.891596ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:54:22.58027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"892.34225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:22.580913Z","caller":"traceutil/trace.go:171","msg":"trace[140414974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:508; }","duration":"892.993711ms","start":"2026-02-10T18:54:21.687913Z","end":"2026-02-10T18:54:22.580907Z","steps":["trace[140414974] 'agreement among raft nodes before linearized reading' (duration: 892.34549ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:22.580937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:21.687907Z","time spent":"893.023832ms","remote":"127.0.0.1:36152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:54:23.427723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.791664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" ","response":"range_response_count:1 size:4083"} {"level":"info","ts":"2026-02-10T18:54:23.427796Z","caller":"traceutil/trace.go:171","msg":"trace[829231425] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io; range_end:; response_count:1; response_revision:518; }","duration":"446.907688ms","start":"2026-02-10T18:54:22.980873Z","end":"2026-02-10T18:54:23.42778Z","steps":["trace[829231425] 'range keys from in-memory index tree' (duration: 446.67181ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:23.427826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:22.980856Z","time spent":"446.962319ms","remote":"127.0.0.1:36186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4106,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumpodippools.cilium.io\" "} {"level":"warn","ts":"2026-02-10T18:54:23.428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.980976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.106\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:54:23.428019Z","caller":"traceutil/trace.go:171","msg":"trace[734835030] range","detail":"{range_begin:/registry/masterleases/199.19.213.106; range_end:; response_count:1; response_revision:518; }","duration":"382.033328ms","start":"2026-02-10T18:54:23.045979Z","end":"2026-02-10T18:54:23.428013Z","steps":["trace[734835030] 'range keys from in-memory index tree' (duration: 381.920864ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:23.428035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:23.045962Z","time spent":"382.068709ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.106\" "} {"level":"warn","ts":"2026-02-10T18:54:23.428147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.209497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-02-10T18:54:23.428165Z","caller":"traceutil/trace.go:171","msg":"trace[24218427] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:518; }","duration":"247.259839ms","start":"2026-02-10T18:54:23.180899Z","end":"2026-02-10T18:54:23.428159Z","steps":["trace[24218427] 'range keys from in-memory index tree' (duration: 247.129404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:23.428247Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.451532ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:23.428263Z","caller":"traceutil/trace.go:171","msg":"trace[561674294] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"151.487583ms","start":"2026-02-10T18:54:23.276771Z","end":"2026-02-10T18:54:23.428258Z","steps":["trace[561674294] 'range keys from in-memory index tree' (duration: 151.39198ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:23.636712Z","caller":"traceutil/trace.go:171","msg":"trace[100305643] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"204.36155ms","start":"2026-02-10T18:54:23.432326Z","end":"2026-02-10T18:54:23.636688Z","steps":["trace[100305643] 'process raft request' (duration: 188.666429ms)","trace[100305643] 'compare' (duration: 15.35654ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:23.637393Z","caller":"traceutil/trace.go:171","msg":"trace[1222763763] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"197.182301ms","start":"2026-02-10T18:54:23.440198Z","end":"2026-02-10T18:54:23.63738Z","steps":["trace[1222763763] 'process raft request' (duration: 196.901912ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:24.585158Z","caller":"traceutil/trace.go:171","msg":"trace[744432998] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"221.119555ms","start":"2026-02-10T18:54:24.36402Z","end":"2026-02-10T18:54:24.585139Z","steps":["trace[744432998] 'process raft request' (duration: 220.896918ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:24.589849Z","caller":"traceutil/trace.go:171","msg":"trace[213134823] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:538; }","duration":"199.003099ms","start":"2026-02-10T18:54:24.390831Z","end":"2026-02-10T18:54:24.589835Z","steps":["trace[213134823] 'read index received' (duration: 198.925607ms)","trace[213134823] 'applied index is now lower than readState.Index' (duration: 76.112µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:24.591254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.406774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-02-10T18:54:24.595926Z","caller":"traceutil/trace.go:171","msg":"trace[1096944240] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:523; }","duration":"205.083693ms","start":"2026-02-10T18:54:24.390806Z","end":"2026-02-10T18:54:24.59589Z","steps":["trace[1096944240] 'agreement among raft nodes before linearized reading' (duration: 200.341301ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:24.600631Z","caller":"traceutil/trace.go:171","msg":"trace[641366654] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"206.013753ms","start":"2026-02-10T18:54:24.394608Z","end":"2026-02-10T18:54:24.600622Z","steps":["trace[641366654] 'process raft request' (duration: 205.60798ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:29.002487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.397315ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:29.002603Z","caller":"traceutil/trace.go:171","msg":"trace[1394869138] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:555; }","duration":"268.411154ms","start":"2026-02-10T18:54:28.734178Z","end":"2026-02-10T18:54:29.002589Z","steps":["trace[1394869138] 'read index received' (duration: 78.34215ms)","trace[1394869138] 'applied index is now lower than readState.Index' (duration: 190.067784ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:29.002677Z","caller":"traceutil/trace.go:171","msg":"trace[203558732] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"268.879798ms","start":"2026-02-10T18:54:28.733788Z","end":"2026-02-10T18:54:29.002668Z","steps":["trace[203558732] 'process raft request' (duration: 151.202034ms)","trace[203558732] 'compare' (duration: 117.282242ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:29.002808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.816347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T18:54:29.002861Z","caller":"traceutil/trace.go:171","msg":"trace[466615367] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:541; }","duration":"216.90933ms","start":"2026-02-10T18:54:28.785938Z","end":"2026-02-10T18:54:29.002847Z","steps":["trace[466615367] 'agreement among raft nodes before linearized reading' (duration: 216.776646ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:29.003021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.846197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:54:29.00305Z","caller":"traceutil/trace.go:171","msg":"trace[1550648088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:541; }","duration":"268.905159ms","start":"2026-02-10T18:54:28.734136Z","end":"2026-02-10T18:54:29.003041Z","steps":["trace[1550648088] 'agreement among raft nodes before linearized reading' (duration: 268.797806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:29.249063Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.069432ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:29.249187Z","caller":"traceutil/trace.go:171","msg":"trace[1244529051] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"243.378375ms","start":"2026-02-10T18:54:29.005796Z","end":"2026-02-10T18:54:29.249174Z","steps":["trace[1244529051] 'process raft request' (duration: 112.119387ms)","trace[1244529051] 'compare' (duration: 130.987289ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:29.249327Z","caller":"traceutil/trace.go:171","msg":"trace[1102542809] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"242.470526ms","start":"2026-02-10T18:54:29.006831Z","end":"2026-02-10T18:54:29.249302Z","steps":["trace[1102542809] 'process raft request' (duration: 242.313551ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.126362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.812264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-10T18:54:31.126542Z","caller":"traceutil/trace.go:171","msg":"trace[187459103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"121.034622ms","start":"2026-02-10T18:54:31.005493Z","end":"2026-02-10T18:54:31.126528Z","steps":["trace[187459103] 'range keys from in-memory index tree' (duration: 120.695821ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.382257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.396577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T18:54:31.38233Z","caller":"traceutil/trace.go:171","msg":"trace[1898608085] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:546; }","duration":"128.50637ms","start":"2026-02-10T18:54:31.253807Z","end":"2026-02-10T18:54:31.382313Z","steps":["trace[1898608085] 'range keys from in-memory index tree' (duration: 128.226661ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:31.382848Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.078708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:54:31.382884Z","caller":"traceutil/trace.go:171","msg":"trace[1703594669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"129.165681ms","start":"2026-02-10T18:54:31.253707Z","end":"2026-02-10T18:54:31.382873Z","steps":["trace[1703594669] 'range keys from in-memory index tree' (duration: 128.870032ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:33.162364Z","caller":"traceutil/trace.go:171","msg":"trace[1795123882] linearizableReadLoop","detail":"{readStateIndex:569; appliedIndex:568; }","duration":"469.605063ms","start":"2026-02-10T18:54:32.692735Z","end":"2026-02-10T18:54:33.16234Z","steps":["trace[1795123882] 'read index received' (duration: 468.945032ms)","trace[1795123882] 'applied index is now lower than readState.Index' (duration: 658.351µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:33.162611Z","caller":"traceutil/trace.go:171","msg":"trace[527846520] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"524.501754ms","start":"2026-02-10T18:54:32.638098Z","end":"2026-02-10T18:54:33.1626Z","steps":["trace[527846520] 'process raft request' (duration: 523.652827ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:33.162706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:32.63807Z","time spent":"524.568446ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:33.162881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.15251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:33.162912Z","caller":"traceutil/trace.go:171","msg":"trace[458465131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:553; }","duration":"470.221001ms","start":"2026-02-10T18:54:32.692681Z","end":"2026-02-10T18:54:33.162902Z","steps":["trace[458465131] 'agreement among raft nodes before linearized reading' (duration: 470.17127ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:33.162938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:32.692658Z","time spent":"470.274033ms","remote":"127.0.0.1:36156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:54:33.163132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.038641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p74rz\" ","response":"range_response_count:1 size:16656"} {"level":"info","ts":"2026-02-10T18:54:33.163158Z","caller":"traceutil/trace.go:171","msg":"trace[1321398614] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p74rz; range_end:; response_count:1; response_revision:553; }","duration":"378.094133ms","start":"2026-02-10T18:54:32.785056Z","end":"2026-02-10T18:54:33.16315Z","steps":["trace[1321398614] 'agreement among raft nodes before linearized reading' (duration: 377.99069ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:33.163183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:32.785Z","time spent":"378.177005ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16679,"request content":"key:\"/registry/pods/kube-system/cilium-p74rz\" "} {"level":"warn","ts":"2026-02-10T18:54:33.16345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.129377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.106\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:54:33.163478Z","caller":"traceutil/trace.go:171","msg":"trace[1093037209] range","detail":"{range_begin:/registry/masterleases/199.19.213.106; range_end:; response_count:1; response_revision:553; }","duration":"117.157918ms","start":"2026-02-10T18:54:33.046312Z","end":"2026-02-10T18:54:33.16347Z","steps":["trace[1093037209] 'agreement among raft nodes before linearized reading' (duration: 117.074075ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:33.163635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.128505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:54:33.163661Z","caller":"traceutil/trace.go:171","msg":"trace[1330122349] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"331.190956ms","start":"2026-02-10T18:54:32.832461Z","end":"2026-02-10T18:54:33.163652Z","steps":["trace[1330122349] 'agreement among raft nodes before linearized reading' (duration: 331.123644ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:33.163691Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:32.832438Z","time spent":"331.240848ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:54:33.38292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.412797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:33.383088Z","caller":"traceutil/trace.go:171","msg":"trace[1003798702] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"202.696227ms","start":"2026-02-10T18:54:33.180378Z","end":"2026-02-10T18:54:33.383074Z","steps":["trace[1003798702] 'process raft request' (duration: 202.622945ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:33.383306Z","caller":"traceutil/trace.go:171","msg":"trace[1453649134] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"213.431049ms","start":"2026-02-10T18:54:33.169868Z","end":"2026-02-10T18:54:33.383299Z","steps":["trace[1453649134] 'process raft request' (duration: 105.580018ms)","trace[1453649134] 'compare' (duration: 107.18857ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:34.645588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.058657ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:34.645671Z","caller":"traceutil/trace.go:171","msg":"trace[583344067] linearizableReadLoop","detail":"{readStateIndex:573; appliedIndex:570; }","duration":"1.436779769s","start":"2026-02-10T18:54:33.208876Z","end":"2026-02-10T18:54:34.645655Z","steps":["trace[583344067] 'read index received' (duration: 66.647247ms)","trace[583344067] 'applied index is now lower than readState.Index' (duration: 1.370130972s)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:34.64575Z","caller":"traceutil/trace.go:171","msg":"trace[1566099788] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"1.43904787s","start":"2026-02-10T18:54:33.206689Z","end":"2026-02-10T18:54:34.645736Z","steps":["trace[1566099788] 'process raft request' (duration: 910.713224ms)","trace[1566099788] 'compare' (duration: 527.540171ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:34.645813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.206662Z","time spent":"1.439127543s","remote":"127.0.0.1:36170","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-02-10T18:54:34.646066Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.187993131s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-02-10T18:54:34.646102Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.437223853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:54:34.646129Z","caller":"traceutil/trace.go:171","msg":"trace[1165728948] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:556; }","duration":"1.437269484s","start":"2026-02-10T18:54:33.208851Z","end":"2026-02-10T18:54:34.646121Z","steps":["trace[1165728948] 'agreement among raft nodes before linearized reading' (duration: 1.437103998s)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:34.646117Z","caller":"traceutil/trace.go:171","msg":"trace[335017856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.188070764s","start":"2026-02-10T18:54:33.458033Z","end":"2026-02-10T18:54:34.646104Z","steps":["trace[335017856] 'agreement among raft nodes before linearized reading' (duration: 1.187979571s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:34.646158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.208845Z","time spent":"1.437306786s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-10T18:54:34.646165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.458023Z","time spent":"1.188131386s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:34.646278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"847.466938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:54:34.646295Z","caller":"traceutil/trace.go:171","msg":"trace[2106980624] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"847.556411ms","start":"2026-02-10T18:54:33.798733Z","end":"2026-02-10T18:54:34.646289Z","steps":["trace[2106980624] 'agreement among raft nodes before linearized reading' (duration: 847.51444ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:34.646311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.798714Z","time spent":"847.593072ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:34.646417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"954.843913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:34.646469Z","caller":"traceutil/trace.go:171","msg":"trace[1609550253] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"954.931266ms","start":"2026-02-10T18:54:33.691527Z","end":"2026-02-10T18:54:34.646458Z","steps":["trace[1609550253] 'agreement among raft nodes before linearized reading' (duration: 954.869974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:34.646507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.691506Z","time spent":"954.987758ms","remote":"127.0.0.1:36152","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-10T18:54:34.646517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.184986895s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:54:34.646551Z","caller":"traceutil/trace.go:171","msg":"trace[2093445429] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:556; }","duration":"1.185035447s","start":"2026-02-10T18:54:33.461499Z","end":"2026-02-10T18:54:34.646535Z","steps":["trace[2093445429] 'agreement among raft nodes before linearized reading' (duration: 1.184990136s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:34.646576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:33.461494Z","time spent":"1.185074028s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-02-10T18:54:35.086701Z","caller":"traceutil/trace.go:171","msg":"trace[1831043580] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"433.578173ms","start":"2026-02-10T18:54:34.653105Z","end":"2026-02-10T18:54:35.086683Z","steps":["trace[1831043580] 'process raft request' (duration: 433.463159ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:35.086819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:34.653095Z","time spent":"433.661995ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:35.087292Z","caller":"traceutil/trace.go:171","msg":"trace[1179851267] linearizableReadLoop","detail":"{readStateIndex:574; appliedIndex:574; }","duration":"433.748448ms","start":"2026-02-10T18:54:34.653536Z","end":"2026-02-10T18:54:35.087284Z","steps":["trace[1179851267] 'read index received' (duration: 433.740158ms)","trace[1179851267] 'applied index is now lower than readState.Index' (duration: 2.44µs)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:35.087397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"433.857071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-02-10T18:54:35.087416Z","caller":"traceutil/trace.go:171","msg":"trace[327025991] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:557; }","duration":"433.888993ms","start":"2026-02-10T18:54:34.653522Z","end":"2026-02-10T18:54:35.087411Z","steps":["trace[327025991] 'agreement among raft nodes before linearized reading' (duration: 433.78962ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:35.087435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:34.653517Z","time spent":"433.913544ms","remote":"127.0.0.1:36170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" "} {"level":"info","ts":"2026-02-10T18:54:35.08967Z","caller":"traceutil/trace.go:171","msg":"trace[485946763] transaction","detail":"{read_only:false; response_revision:559; number_of_response:1; }","duration":"435.954449ms","start":"2026-02-10T18:54:34.653677Z","end":"2026-02-10T18:54:35.089631Z","steps":["trace[485946763] 'process raft request' (duration: 435.687671ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:35.089993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:34.653674Z","time spent":"436.048702ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:35.090257Z","caller":"traceutil/trace.go:171","msg":"trace[745380238] transaction","detail":"{read_only:false; response_revision:558; number_of_response:1; }","duration":"436.594079ms","start":"2026-02-10T18:54:34.653648Z","end":"2026-02-10T18:54:35.090242Z","steps":["trace[745380238] 'process raft request' (duration: 435.637999ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:35.090336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:34.653643Z","time spent":"436.652221ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:35.089674Z","caller":"traceutil/trace.go:171","msg":"trace[1378238518] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"435.961209ms","start":"2026-02-10T18:54:34.653698Z","end":"2026-02-10T18:54:35.089659Z","steps":["trace[1378238518] 'process raft request' (duration: 435.710371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:35.090427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:34.653695Z","time spent":"436.681902ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:37.965773Z","caller":"traceutil/trace.go:171","msg":"trace[1705232941] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"135.597536ms","start":"2026-02-10T18:54:37.830157Z","end":"2026-02-10T18:54:37.965754Z","steps":["trace[1705232941] 'process raft request' (duration: 99.834545ms)","trace[1705232941] 'compare' (duration: 35.675588ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:41.95967Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"271.623286ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:41.959731Z","caller":"traceutil/trace.go:171","msg":"trace[1330849487] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"271.727769ms","start":"2026-02-10T18:54:41.687988Z","end":"2026-02-10T18:54:41.959715Z","steps":["trace[1330849487] 'range keys from in-memory index tree' (duration: 271.556934ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:41.959987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.609259ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-p74rz\" ","response":"range_response_count:1 size:18075"} {"level":"info","ts":"2026-02-10T18:54:41.960021Z","caller":"traceutil/trace.go:171","msg":"trace[1009670881] range","detail":"{range_begin:/registry/pods/kube-system/cilium-p74rz; range_end:; response_count:1; response_revision:601; }","duration":"110.666911ms","start":"2026-02-10T18:54:41.849333Z","end":"2026-02-10T18:54:41.96Z","steps":["trace[1009670881] 'range keys from in-memory index tree' (duration: 110.457704ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:42.226368Z","caller":"traceutil/trace.go:171","msg":"trace[1103894682] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"251.553086ms","start":"2026-02-10T18:54:41.974799Z","end":"2026-02-10T18:54:42.226352Z","steps":["trace[1103894682] 'process raft request' (duration: 251.448743ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:47.316342Z","caller":"traceutil/trace.go:171","msg":"trace[1655291494] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"317.690106ms","start":"2026-02-10T18:54:46.998631Z","end":"2026-02-10T18:54:47.316321Z","steps":["trace[1655291494] 'process raft request' (duration: 249.829941ms)","trace[1655291494] 'compare' (duration: 67.781532ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:47.3165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:46.998601Z","time spent":"317.80364ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3798,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:47.363205Z","caller":"traceutil/trace.go:171","msg":"trace[960798649] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"364.53157ms","start":"2026-02-10T18:54:46.998655Z","end":"2026-02-10T18:54:47.363186Z","steps":["trace[960798649] 'process raft request' (duration: 364.171049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.363328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:46.998634Z","time spent":"364.619473ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:47.363614Z","caller":"traceutil/trace.go:171","msg":"trace[1849400744] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"364.807148ms","start":"2026-02-10T18:54:46.9988Z","end":"2026-02-10T18:54:47.363607Z","steps":["trace[1849400744] 'process raft request' (duration: 364.131117ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.363674Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:46.998795Z","time spent":"364.858291ms","remote":"127.0.0.1:36350","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3834,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:47.365963Z","caller":"traceutil/trace.go:171","msg":"trace[1673672485] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"362.558397ms","start":"2026-02-10T18:54:47.00339Z","end":"2026-02-10T18:54:47.365949Z","steps":["trace[1673672485] 'process raft request' (duration: 359.567042ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.366155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:47.00338Z","time spent":"362.699501ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4635,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:47.366426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.287526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-10T18:54:47.366509Z","caller":"traceutil/trace.go:171","msg":"trace[2095253434] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"224.386739ms","start":"2026-02-10T18:54:47.142085Z","end":"2026-02-10T18:54:47.366472Z","steps":["trace[2095253434] 'agreement among raft nodes before linearized reading' (duration: 224.194423ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:47.368298Z","caller":"traceutil/trace.go:171","msg":"trace[437507085] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:648; }","duration":"226.149866ms","start":"2026-02-10T18:54:47.142138Z","end":"2026-02-10T18:54:47.368287Z","steps":["trace[437507085] 'read index received' (duration: 106.349464ms)","trace[437507085] 'applied index is now lower than readState.Index' (duration: 115.059641ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:47.368616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.697344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-02-10T18:54:47.368649Z","caller":"traceutil/trace.go:171","msg":"trace[311855942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"190.767096ms","start":"2026-02-10T18:54:47.177867Z","end":"2026-02-10T18:54:47.368635Z","steps":["trace[311855942] 'agreement among raft nodes before linearized reading' (duration: 190.675803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.368818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.267015ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-10T18:54:47.36885Z","caller":"traceutil/trace.go:171","msg":"trace[1181452582] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:633; }","duration":"192.299766ms","start":"2026-02-10T18:54:47.176546Z","end":"2026-02-10T18:54:47.368846Z","steps":["trace[1181452582] 'agreement among raft nodes before linearized reading' (duration: 192.241724ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:47.369022Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.463183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:54:47.369043Z","caller":"traceutil/trace.go:171","msg":"trace[2119100319] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:633; }","duration":"225.503875ms","start":"2026-02-10T18:54:47.143534Z","end":"2026-02-10T18:54:47.369038Z","steps":["trace[2119100319] 'agreement among raft nodes before linearized reading' (duration: 224.836603ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:51.039183Z","caller":"traceutil/trace.go:171","msg":"trace[579935424] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"150.532743ms","start":"2026-02-10T18:54:50.888621Z","end":"2026-02-10T18:54:51.039154Z","steps":["trace[579935424] 'process raft request' (duration: 150.404429ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:55.930462Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3822320545682348791,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-10T18:54:56.141216Z","caller":"traceutil/trace.go:171","msg":"trace[1084847365] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"732.170561ms","start":"2026-02-10T18:54:55.409023Z","end":"2026-02-10T18:54:56.141193Z","steps":["trace[1084847365] 'process raft request' (duration: 732.002625ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.141583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.409011Z","time spent":"732.434089ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:56.541853Z","caller":"traceutil/trace.go:171","msg":"trace[1446646411] linearizableReadLoop","detail":"{readStateIndex:726; appliedIndex:724; }","duration":"1.112261607s","start":"2026-02-10T18:54:55.429574Z","end":"2026-02-10T18:54:56.541836Z","steps":["trace[1446646411] 'read index received' (duration: 712.465241ms)","trace[1446646411] 'applied index is now lower than readState.Index' (duration: 399.795186ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:54:56.54264Z","caller":"traceutil/trace.go:171","msg":"trace[1703811933] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"939.617819ms","start":"2026-02-10T18:54:55.603012Z","end":"2026-02-10T18:54:56.54263Z","steps":["trace[1703811933] 'process raft request' (duration: 938.686239ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.542744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.602995Z","time spent":"939.696681ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:56.542939Z","caller":"traceutil/trace.go:171","msg":"trace[949424614] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"662.814448ms","start":"2026-02-10T18:54:55.880116Z","end":"2026-02-10T18:54:56.542931Z","steps":["trace[949424614] 'process raft request' (duration: 661.687612ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.542976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.880098Z","time spent":"662.856878ms","remote":"127.0.0.1:36338","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4769,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:54:56.54311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.113541737s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:54:56.543131Z","caller":"traceutil/trace.go:171","msg":"trace[621524283] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.11359521s","start":"2026-02-10T18:54:55.429529Z","end":"2026-02-10T18:54:56.543124Z","steps":["trace[621524283] 'agreement among raft nodes before linearized reading' (duration: 1.113520167s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.54315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.429489Z","time spent":"1.113656582s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:56.543262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.109996484s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-10T18:54:56.54328Z","caller":"traceutil/trace.go:171","msg":"trace[29013104] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.110014175s","start":"2026-02-10T18:54:55.433261Z","end":"2026-02-10T18:54:56.543275Z","steps":["trace[29013104] 'agreement among raft nodes before linearized reading' (duration: 1.109961863s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.543298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.433236Z","time spent":"1.110059256s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:56.543456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.952817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:56.543475Z","caller":"traceutil/trace.go:171","msg":"trace[1385128250] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:704; }","duration":"859.999959ms","start":"2026-02-10T18:54:55.68347Z","end":"2026-02-10T18:54:56.54347Z","steps":["trace[1385128250] 'agreement among raft nodes before linearized reading' (duration: 859.967618ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.543491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.683456Z","time spent":"860.030949ms","remote":"127.0.0.1:36300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-10T18:54:56.543618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"982.211497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:54:56.543635Z","caller":"traceutil/trace.go:171","msg":"trace[895122998] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:704; }","duration":"982.249619ms","start":"2026-02-10T18:54:55.56138Z","end":"2026-02-10T18:54:56.54363Z","steps":["trace[895122998] 'agreement among raft nodes before linearized reading' (duration: 982.200107ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.54365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.561365Z","time spent":"982.28105ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-10T18:54:56.54377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.110135399s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:54:56.543787Z","caller":"traceutil/trace.go:171","msg":"trace[1519889481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:704; }","duration":"1.11016398s","start":"2026-02-10T18:54:55.433618Z","end":"2026-02-10T18:54:56.543782Z","steps":["trace[1519889481] 'agreement among raft nodes before linearized reading' (duration: 1.110118288s)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.543802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:55.433612Z","time spent":"1.11018614s","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-02-10T18:54:56.891081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.754868ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:54:56.891177Z","caller":"traceutil/trace.go:171","msg":"trace[729192779] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"339.895854ms","start":"2026-02-10T18:54:56.551263Z","end":"2026-02-10T18:54:56.891159Z","steps":["trace[729192779] 'process raft request' (duration: 210.987311ms)","trace[729192779] 'compare' (duration: 128.650565ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:56.891262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:56.551248Z","time spent":"339.960306ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:56.94606Z","caller":"traceutil/trace.go:171","msg":"trace[225453002] linearizableReadLoop","detail":"{readStateIndex:730; appliedIndex:726; }","duration":"253.937622ms","start":"2026-02-10T18:54:56.692106Z","end":"2026-02-10T18:54:56.946044Z","steps":["trace[225453002] 'read index received' (duration: 70.155868ms)","trace[225453002] 'applied index is now lower than readState.Index' (duration: 183.780854ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:54:56.94615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.037445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-10T18:54:56.946166Z","caller":"traceutil/trace.go:171","msg":"trace[1059991349] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:708; }","duration":"254.103937ms","start":"2026-02-10T18:54:56.692057Z","end":"2026-02-10T18:54:56.946161Z","steps":["trace[1059991349] 'agreement among raft nodes before linearized reading' (duration: 254.046325ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:54:56.946376Z","caller":"traceutil/trace.go:171","msg":"trace[304943204] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"393.942808ms","start":"2026-02-10T18:54:56.552427Z","end":"2026-02-10T18:54:56.94637Z","steps":["trace[304943204] 'process raft request' (duration: 393.482393ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.946421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:56.552406Z","time spent":"393.982139ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:56.946492Z","caller":"traceutil/trace.go:171","msg":"trace[485840100] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"391.803069ms","start":"2026-02-10T18:54:56.554685Z","end":"2026-02-10T18:54:56.946488Z","steps":["trace[485840100] 'process raft request' (duration: 391.307364ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.946515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:56.554676Z","time spent":"391.825791ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:54:56.946576Z","caller":"traceutil/trace.go:171","msg":"trace[2015463677] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"390.334003ms","start":"2026-02-10T18:54:56.556238Z","end":"2026-02-10T18:54:56.946572Z","steps":["trace[2015463677] 'process raft request' (duration: 389.784896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:54:56.946598Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:54:56.556229Z","time spent":"390.356524ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-10T18:55:11.056618Z","caller":"traceutil/trace.go:171","msg":"trace[1354661381] transaction","detail":"{read_only:false; response_revision:749; number_of_response:1; }","duration":"111.159106ms","start":"2026-02-10T18:55:10.945433Z","end":"2026-02-10T18:55:11.056592Z","steps":["trace[1354661381] 'process raft request' (duration: 110.65183ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:30.689091Z","caller":"traceutil/trace.go:171","msg":"trace[773004633] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"315.337681ms","start":"2026-02-10T18:55:30.373721Z","end":"2026-02-10T18:55:30.689059Z","steps":["trace[773004633] 'process raft request' (duration: 315.195417ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:30.689235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:55:30.373712Z","time spent":"315.448484ms","remote":"127.0.0.1:36428","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-10T18:55:31.340587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.119247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-02-10T18:55:31.340637Z","caller":"traceutil/trace.go:171","msg":"trace[1344993044] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:865; }","duration":"207.21431ms","start":"2026-02-10T18:55:31.133412Z","end":"2026-02-10T18:55:31.340626Z","steps":["trace[1344993044] 'range keys from in-memory index tree' (duration: 207.042926ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:31.340732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.161685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-02-10T18:55:31.340746Z","caller":"traceutil/trace.go:171","msg":"trace[273666655] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:865; }","duration":"157.217476ms","start":"2026-02-10T18:55:31.183523Z","end":"2026-02-10T18:55:31.340741Z","steps":["trace[273666655] 'range keys from in-memory index tree' (duration: 157.072481ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:41.657007Z","caller":"traceutil/trace.go:171","msg":"trace[145876769] linearizableReadLoop","detail":"{readStateIndex:927; appliedIndex:926; }","duration":"209.531025ms","start":"2026-02-10T18:55:41.447455Z","end":"2026-02-10T18:55:41.656986Z","steps":["trace[145876769] 'read index received' (duration: 209.365269ms)","trace[145876769] 'applied index is now lower than readState.Index' (duration: 164.626µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:41.657306Z","caller":"traceutil/trace.go:171","msg":"trace[1346785690] transaction","detail":"{read_only:false; response_revision:894; number_of_response:1; }","duration":"215.391912ms","start":"2026-02-10T18:55:41.441903Z","end":"2026-02-10T18:55:41.657295Z","steps":["trace[1346785690] 'process raft request' (duration: 214.978509ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:41.657588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.137164ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-10T18:55:41.657623Z","caller":"traceutil/trace.go:171","msg":"trace[1384456442] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:894; }","duration":"210.198287ms","start":"2026-02-10T18:55:41.447415Z","end":"2026-02-10T18:55:41.657614Z","steps":["trace[1384456442] 'agreement among raft nodes before linearized reading' (duration: 210.044242ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:41.657822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.596075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:55:41.657849Z","caller":"traceutil/trace.go:171","msg":"trace[1547546492] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:894; }","duration":"192.643726ms","start":"2026-02-10T18:55:41.465198Z","end":"2026-02-10T18:55:41.657841Z","steps":["trace[1547546492] 'agreement among raft nodes before linearized reading' (duration: 192.564793ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:41.658088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.844943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-10T18:55:41.658122Z","caller":"traceutil/trace.go:171","msg":"trace[720463273] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:894; }","duration":"192.886124ms","start":"2026-02-10T18:55:41.465228Z","end":"2026-02-10T18:55:41.658114Z","steps":["trace[720463273] 'agreement among raft nodes before linearized reading' (duration: 192.796221ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:55:55.967889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.690622ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:55:55.967993Z","caller":"traceutil/trace.go:171","msg":"trace[921874016] linearizableReadLoop","detail":"{readStateIndex:1030; appliedIndex:1029; }","duration":"168.190296ms","start":"2026-02-10T18:55:55.799776Z","end":"2026-02-10T18:55:55.967966Z","steps":["trace[921874016] 'read index received' (duration: 41.288327ms)","trace[921874016] 'applied index is now lower than readState.Index' (duration: 126.900509ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:55.96814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.358891ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-10T18:55:55.968173Z","caller":"traceutil/trace.go:171","msg":"trace[339255814] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:994; }","duration":"168.404413ms","start":"2026-02-10T18:55:55.799759Z","end":"2026-02-10T18:55:55.968163Z","steps":["trace[339255814] 'agreement among raft nodes before linearized reading' (duration: 168.266379ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:55.96842Z","caller":"traceutil/trace.go:171","msg":"trace[102716541] transaction","detail":"{read_only:false; response_revision:994; number_of_response:1; }","duration":"177.436281ms","start":"2026-02-10T18:55:55.790971Z","end":"2026-02-10T18:55:55.968408Z","steps":["trace[102716541] 'process raft request' (duration: 50.1561ms)","trace[102716541] 'compare' (duration: 126.481636ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:56.131641Z","caller":"traceutil/trace.go:171","msg":"trace[813866053] transaction","detail":"{read_only:false; response_revision:995; number_of_response:1; }","duration":"156.75392ms","start":"2026-02-10T18:55:55.97487Z","end":"2026-02-10T18:55:56.131624Z","steps":["trace[813866053] 'process raft request' (duration: 144.21278ms)","trace[813866053] 'compare' (duration: 12.454378ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:56.444129Z","caller":"traceutil/trace.go:171","msg":"trace[1424028957] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"217.52666ms","start":"2026-02-10T18:55:56.226577Z","end":"2026-02-10T18:55:56.444104Z","steps":["trace[1424028957] 'process raft request' (duration: 144.384807ms)","trace[1424028957] 'compare' (duration: 73.025699ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:55:56.757096Z","caller":"traceutil/trace.go:171","msg":"trace[1901401641] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"176.628605ms","start":"2026-02-10T18:55:56.580444Z","end":"2026-02-10T18:55:56.757073Z","steps":["trace[1901401641] 'process raft request' (duration: 176.277314ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:56.974259Z","caller":"traceutil/trace.go:171","msg":"trace[1784545301] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"149.541301ms","start":"2026-02-10T18:55:56.824692Z","end":"2026-02-10T18:55:56.974234Z","steps":["trace[1784545301] 'process raft request' (duration: 130.0866ms)","trace[1784545301] 'compare' (duration: 19.228744ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:55:57.337524Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.053962ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-10T18:55:57.337575Z","caller":"traceutil/trace.go:171","msg":"trace[46028301] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1000; }","duration":"184.127855ms","start":"2026-02-10T18:55:57.153435Z","end":"2026-02-10T18:55:57.337563Z","steps":["trace[46028301] 'range keys from in-memory index tree' (duration: 183.903157ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:55:57.550386Z","caller":"traceutil/trace.go:171","msg":"trace[2105686734] transaction","detail":"{read_only:false; response_revision:1001; number_of_response:1; }","duration":"207.224121ms","start":"2026-02-10T18:55:57.343139Z","end":"2026-02-10T18:55:57.550364Z","steps":["trace[2105686734] 'process raft request' (duration: 207.095337ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:58:12.939351Z","caller":"traceutil/trace.go:171","msg":"trace[1687022806] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"141.089082ms","start":"2026-02-10T18:58:12.798244Z","end":"2026-02-10T18:58:12.939333Z","steps":["trace[1687022806] 'process raft request' (duration: 140.998619ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:58:43.614989Z","caller":"traceutil/trace.go:171","msg":"trace[796094200] transaction","detail":"{read_only:false; response_revision:1763; number_of_response:1; }","duration":"163.761093ms","start":"2026-02-10T18:58:43.451172Z","end":"2026-02-10T18:58:43.614933Z","steps":["trace[796094200] 'process raft request' (duration: 146.487477ms)","trace[796094200] 'compare' (duration: 16.625276ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:59:10.408593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.135308ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-10T18:59:10.408904Z","caller":"traceutil/trace.go:171","msg":"trace[1696301611] transaction","detail":"{read_only:false; response_revision:1909; number_of_response:1; }","duration":"338.185069ms","start":"2026-02-10T18:59:10.0707Z","end":"2026-02-10T18:59:10.408886Z","steps":["trace[1696301611] 'process raft request' (duration: 161.71856ms)","trace[1696301611] 'compare' (duration: 175.833389ms)"],"step_count":2} {"level":"warn","ts":"2026-02-10T18:59:10.409052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-10T18:59:10.070689Z","time spent":"338.323613ms","remote":"127.0.0.1:36212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":705,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-10T18:59:10.921712Z","caller":"traceutil/trace.go:171","msg":"trace[285815060] transaction","detail":"{read_only:false; response_revision:1912; number_of_response:1; }","duration":"110.056177ms","start":"2026-02-10T18:59:10.811626Z","end":"2026-02-10T18:59:10.921682Z","steps":["trace[285815060] 'process raft request' (duration: 58.462785ms)","trace[285815060] 'compare' (duration: 51.145878ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:59:10.922593Z","caller":"traceutil/trace.go:171","msg":"trace[917183473] linearizableReadLoop","detail":"{readStateIndex:1990; appliedIndex:1987; }","duration":"107.236329ms","start":"2026-02-10T18:59:10.815343Z","end":"2026-02-10T18:59:10.922579Z","steps":["trace[917183473] 'read index received' (duration: 54.757779ms)","trace[917183473] 'applied index is now lower than readState.Index' (duration: 52.47736ms)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:59:10.924195Z","caller":"traceutil/trace.go:171","msg":"trace[1729131632] transaction","detail":"{read_only:false; response_revision:1913; number_of_response:1; }","duration":"109.906932ms","start":"2026-02-10T18:59:10.814273Z","end":"2026-02-10T18:59:10.92418Z","steps":["trace[1729131632] 'process raft request' (duration: 107.355762ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:59:10.92523Z","caller":"traceutil/trace.go:171","msg":"trace[1921082910] transaction","detail":"{read_only:false; response_revision:1914; number_of_response:1; }","duration":"110.411928ms","start":"2026-02-10T18:59:10.8148Z","end":"2026-02-10T18:59:10.925212Z","steps":["trace[1921082910] 'process raft request' (duration: 107.727824ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:59:10.929387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.02652ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1243"} {"level":"info","ts":"2026-02-10T18:59:10.929427Z","caller":"traceutil/trace.go:171","msg":"trace[236368004] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:1914; }","duration":"114.076151ms","start":"2026-02-10T18:59:10.81534Z","end":"2026-02-10T18:59:10.929417Z","steps":["trace[236368004] 'agreement among raft nodes before linearized reading' (duration: 113.271406ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:59:13.095793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.27579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-02-10T18:59:13.096313Z","caller":"traceutil/trace.go:171","msg":"trace[712674691] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1925; }","duration":"156.855379ms","start":"2026-02-10T18:59:12.939443Z","end":"2026-02-10T18:59:13.096298Z","steps":["trace[712674691] 'range keys from in-memory index tree' (duration: 156.162547ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:59:13.096912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.897169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-10T18:59:13.097015Z","caller":"traceutil/trace.go:171","msg":"trace[1775643635] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1925; }","duration":"156.024933ms","start":"2026-02-10T18:59:12.940981Z","end":"2026-02-10T18:59:13.097006Z","steps":["trace[1775643635] 'range keys from in-memory index tree' (duration: 155.777025ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:59:13.097407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.621438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-10T18:59:13.097503Z","caller":"traceutil/trace.go:171","msg":"trace[1506276351] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1925; }","duration":"153.730551ms","start":"2026-02-10T18:59:12.943763Z","end":"2026-02-10T18:59:13.097494Z","steps":["trace[1506276351] 'range keys from in-memory index tree' (duration: 153.557516ms)"],"step_count":1} {"level":"info","ts":"2026-02-10T18:59:36.23726Z","caller":"traceutil/trace.go:171","msg":"trace[246555173] linearizableReadLoop","detail":"{readStateIndex:2119; appliedIndex:2118; }","duration":"128.46787ms","start":"2026-02-10T18:59:36.108778Z","end":"2026-02-10T18:59:36.237246Z","steps":["trace[246555173] 'read index received' (duration: 128.334356ms)","trace[246555173] 'applied index is now lower than readState.Index' (duration: 132.304µs)"],"step_count":2} {"level":"info","ts":"2026-02-10T18:59:36.237399Z","caller":"traceutil/trace.go:171","msg":"trace[1302819821] transaction","detail":"{read_only:false; response_revision:2038; number_of_response:1; }","duration":"141.133456ms","start":"2026-02-10T18:59:36.096256Z","end":"2026-02-10T18:59:36.23739Z","steps":["trace[1302819821] 'process raft request' (duration: 140.824397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-10T18:59:36.237794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.002246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:621"} {"level":"info","ts":"2026-02-10T18:59:36.237824Z","caller":"traceutil/trace.go:171","msg":"trace[1228420107] range","detail":"{range_begin:/registry/configmaps/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2038; }","duration":"129.061288ms","start":"2026-02-10T18:59:36.108754Z","end":"2026-02-10T18:59:36.237815Z","steps":["trace[1228420107] 'agreement among raft nodes before linearized reading' (duration: 128.953845ms)"],"step_count":1}